Jul 02 20:51:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:07 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:07 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:33 volumio volumio[1096]: verbose: New Socket.io Connection to 192.168.1.102:3000 from 192.168.1.109 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 02 20:51:37 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:37 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:37 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:37 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:37 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:47 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:47 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:47 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:55 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:56 volumio sudo[964]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 20:51:56 volumio sudo[964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 02 20:51:56 volumio sudo[964]: pam_unix(sudo:session): session closed for user root Jul 02 20:51:56 volumio sudo[966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 20:51:56 volumio sudo[966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 02 20:51:56 volumio sudo[966]: pam_unix(sudo:session): session closed for user root Jul 02 20:51:56 volumio volumio[1096]: verbose: New Socket.io Connection to 192.168.1.102 from 192.168.1.109 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.116 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 02 20:51:56 volumio sudo[972]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 20:51:56 volumio sudo[972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 02 20:51:56 volumio sudo[972]: pam_unix(sudo:session): session closed for user root Jul 02 20:51:56 volumio sudo[974]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 20:51:56 volumio sudo[974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 02 20:51:56 volumio sudo[974]: pam_unix(sudo:session): session closed for user root Jul 02 20:51:56 volumio volumio[1096]: verbose: New Socket.io Connection to 192.168.1.102 from 192.168.1.109 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.116 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 20:51:57 volumio volumio[1096]: info: Received Get System Info Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:57 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:57 volumio volumio[1096]: info: Listing playlists Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 20:51:57 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:51:58 volumio volumio[1096]: info: CoreStateMachine::getQueue Jul 02 20:51:58 volumio volumio[1096]: info: CorePlayQueue::getQueue Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 20:51:58 volumio volumio[1096]: info: Received Get System Info Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:58 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:51:58 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 02 20:51:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 20:51:59 volumio volumio[1096]: info: Received Get System Info Jul 02 20:51:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:51:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:51:59 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:51:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:51:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:52:06 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 02 20:52:06 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 02 20:52:06 volumio dbus-daemon[577]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.222' (uid=0 pid=1012 comm="timedatectl show --property=NTPSynchronized --valu") Jul 02 20:52:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 02 20:52:07 volumio dbus-daemon[577]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 02 20:52:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 02 20:52:07 volumio setdatetime-helper.sh[1011]: Time is already synchronized. Jul 02 20:52:07 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 02 20:52:07 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 02 20:52:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:52:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:52:07 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:52:07 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:07 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:52:11 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 20:52:11 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:52:12 volumio volumio[1096]: info: Preload queue cleared Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 20:52:15 volumio volumio[1096]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 02 20:52:15 volumio volumio[1096]: info: Received Get System Version Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:52:15 volumio volumio[1096]: info: Received Get System Info Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:52:15 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:15 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:52:16 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 20:52:16 volumio volumio[1096]: info: TuneIn handleBrowseUri: tunein/local Jul 02 20:52:17 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:52:17 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:52:17 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:52:17 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:17 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:52:20 volumio volumio[1096]: info: VolumeController::SetAlsaVolume52 Jul 02 20:52:20 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:20 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 50 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:21 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 52 Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 6 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1788822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 30ms Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1788822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1788822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 53ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 51ms Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume53 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789073,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 23ms Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789073,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789073,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 47ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 46ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume54 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:21 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 54 Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume54 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 28 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789200,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789200,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789200,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 67ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 46ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 47ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume55 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume55 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 36 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 34 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789326,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 53ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume56 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:21 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 56 Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789326,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789326,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 120ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 119ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume56 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 60 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 6 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume57 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 41 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 41 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789440,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789440,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789440,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 134ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 80ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 78ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume57 Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume58 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 89 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 6 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume58 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 58 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:21 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 58 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:21 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 69 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 67 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 68 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 68 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 42 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand status took 43 milliseconds Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 201ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 117ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 117ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume59 Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume59 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: Jul 02 20:52:21 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 85 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 82 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 81 milliseconds Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:21 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789566,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:21 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:21 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 193ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 163ms Jul 02 20:52:21 volumio volumio[1096]: info: ------------------------------ 161ms Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume59 Jul 02 20:52:21 volumio volumio[1096]: info: VolumeController::SetAlsaVolume60 Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:21 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:21 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:22 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 109 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 10 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: info: VolumeController::SetAlsaVolume60 Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 36 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 11 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789818,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789818,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789818,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 185ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 86ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 85ms Jul 02 20:52:22 volumio volumio[1096]: info: VolumeController::SetAlsaVolume61 Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 92 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 91 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 91 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789818,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789944,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789944,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 155ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 128ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 127ms Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 32 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1789944,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790070,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790070,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 77ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 49ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 48ms Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 61 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:22 volumio volumio[1096]: info: Preload queue cleared Jul 02 20:52:22 volumio volumio[1096]: info: VolumeController::SetAlsaVolume60 Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: Jul 02 20:52:22 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790699,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 18ms Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 02 20:52:22 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790699,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:22 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:22 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790699,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:22 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:22 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:22 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 37ms Jul 02 20:52:22 volumio volumio[1096]: info: ------------------------------ 37ms Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:22 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume59 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790940,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 16ms Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790940,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1790940,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 33ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 33ms Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 59 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume58 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 60 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:23 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 58 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume58 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791066,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 20ms Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791066,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791066,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 42ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 41ms Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume57 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume57 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 18ms Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 38ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 37ms Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 57 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume56 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 58 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:23 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 56 Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 23ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 23ms Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791192,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 40ms Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume56 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume55 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume55 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 38 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 11 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 9 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume54 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 56 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:23 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 54 Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791318,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791318,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791318,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 100ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 73ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 73ms Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 31 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume53 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791444,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791444,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791444,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 106ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 78ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 78ms Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume53 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 53 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume52 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 31 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 54 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:23 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 52 Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 69ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 42ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 42ms Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 33 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume52 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume51 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 51 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791570,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 113ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 84ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 84ms Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 51 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume51 Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume50 Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 52 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 50 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:23 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 50 Jul 02 20:52:23 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 18 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 12 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 11 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: Jul 02 20:52:23 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 27 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 24 milliseconds Jul 02 20:52:23 volumio volumio[1096]: info: sendMpdCommand status took 23 milliseconds Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:23 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:23 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:23 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:23 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:23 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:23 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 70ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 64ms Jul 02 20:52:23 volumio volumio[1096]: info: ------------------------------ 64ms Jul 02 20:52:23 volumio volumio[1096]: info: VolumeController::SetAlsaVolume49 Jul 02 20:52:24 volumio volumio[1096]: info: VolumeController::SetAlsaVolume48 Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 68 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 67 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 67 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 119ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 117ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 116ms Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 50 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:24 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 47 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: VolumeController::SetAlsaVolume49 Jul 02 20:52:24 volumio volumio[1096]: info: VolumeController::SetAlsaVolume47 Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 51 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 50 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 50 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 50 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 50 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 49 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791822,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 133ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 89ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 89ms Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: VolumeController::SetAlsaVolume46 Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 47 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME 48 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 20:52:24 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 46 Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 87 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 87 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 86 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 85 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 54 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 52 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 165ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 162ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 161ms Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: VolumeController::SetAlsaVolume46 Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 53 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 54 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 53 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 29 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 29 milliseconds Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1791948,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 169ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 135ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 133ms Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: info: Jul 02 20:52:24 volumio volumio[1096]: ---------------------------- MPD announces state update: mixer Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::getState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 19 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand status took 18 milliseconds Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseState Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792074,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 132ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 132ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 106ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 106ms Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 02 20:52:24 volumio volumio[1096]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792200,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ControllerMpd::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Jul 02 20:52:24 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Jul 02 20:52:24 volumio volumio[1096]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1792200,"duration":3960,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Davey Langdon - Good Life 28/06/25","artist":"Davey Langdon","album":null,"uri":"https://audio.mixcloud.stream/secure/hls/,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738,8/f/3/7/9155-9ba9-43dd-bb9f-60ad5ad2c738-192K,.m4a.urlset/index.m3u8","trackType":"m3u8"} Jul 02 20:52:24 volumio volumio[1096]: verbose: CURRENT POSITION 0 Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState stateService play Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:52:24 volumio volumio[1096]: info: Received an update from plugin. extracting info from payload Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreStateMachine::pushState Jul 02 20:52:24 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 76ms Jul 02 20:52:24 volumio volumio[1096]: info: ------------------------------ 76ms Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:24 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jul 02 20:52:25 volumio volumio[1096]: SPOTIFY: SETTING SPOTIFY VOLUME 46 Jul 02 20:52:25 volumio volumio[1096]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 20:52:35 volumio volumio[1096]: Searching plugin music_service/webradio Jul 02 20:52:35 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 02 20:52:35 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:52:35 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:52:35 volumio volumio[1096]: info: Discovery: Getting this device information Jul 02 20:52:35 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Jul 02 20:52:35 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:52:36 volumio volumio[1096]: Use XMLElement.setAttribute instead Jul 02 20:52:36 volumio volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:52:36 volumio volumio[1096]: TypeError: children[i].attr(...).value is not a function Jul 02 20:52:36 volumio volumio[1096]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jul 02 20:52:36 volumio volumio[1096]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 02 20:52:36 volumio volumio[1096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:52:36 volumio sudo[1208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-02 20:51' Jul 02 20:52:36 volumio sudo[1208]: 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"