-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sat 2024-04-13 21:22:59 BST. -- Apr 13 21:21:09 hifi go-librespot[1095]: time="2024-04-13T21:21:09+01:00" level=debug msg="fetched chunk 5/11, size: 524288" Apr 13 21:21:21 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":76,"dbVolume":-30.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:21 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:21 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Apr 13 21:21:21 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":75,"dbVolume":-31.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:21 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:21 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:21 hifi go-librespot[1095]: time="2024-04-13T21:21:21+01:00" level=debug msg="fetched chunk 6/11, size: 524288" Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 76 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 75 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:21:21 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":74,"dbVolume":-33,"mute":false,"disableVolumeControl":false} Apr 13 21:21:21 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:21 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 76 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 74 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 21:21:21 hifi volumio[636]: info: Setting Spotify Volume from Volumio: 74 Apr 13 21:21:21 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-35,"mute":false,"disableVolumeControl":false} Apr 13 21:21:21 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:21 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:21 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:21 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 74 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 73 Apr 13 21:21:21 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:21:22 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":71,"dbVolume":-37.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:22 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:22 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 74 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 71 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 21:21:22 hifi volumio[636]: info: Setting Spotify Volume from Volumio: 71 Apr 13 21:21:22 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":68,"dbVolume":-40.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:22 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:22 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 71 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 68 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 21:21:22 hifi volumio[636]: info: Setting Spotify Volume from Volumio: 68 Apr 13 21:21:22 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":66,"dbVolume":-43.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:22 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:22 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:22 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:22 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 68 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 66 Apr 13 21:21:22 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 21:21:22 hifi volumio[636]: info: Setting Spotify Volume from Volumio: 66 Apr 13 21:21:23 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":66,"dbVolume":-43,"mute":false,"disableVolumeControl":false} Apr 13 21:21:23 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:23 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:23 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:23 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:23 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:23 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:23 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:23 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: SETTING SPOTIFY VOLUME 66 Apr 13 21:21:24 hifi volumio[636]: info: Sending Spotify command with payload to local API: /player/volume Apr 13 21:21:24 hifi go-librespot[1095]: time="2024-04-13T21:21:24+01:00" level=debug msg="update volume to 43253/65535" Apr 13 21:21:24 hifi go-librespot[1095]: time="2024-04-13T21:21:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 13 21:21:24 hifi go-librespot[1095]: time="2024-04-13T21:21:24+01:00" level=trace msg="emitting websocket event: volume" Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 Apr 13 21:21:24 hifi volumio[636]: info: msSurfaceDial volumioupdatevolume callback: {"vol":67,"dbVolume":-42.5,"mute":false,"disableVolumeControl":false} Apr 13 21:21:24 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:21:24 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:24 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:21:24 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:21:24 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:21:24 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:21:24 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 66 Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 67 Apr 13 21:21:24 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:21:33 hifi go-librespot[1095]: time="2024-04-13T21:21:33+01:00" level=debug msg="fetched chunk 7/11, size: 524288" Apr 13 21:21:44 hifi go-librespot[1095]: time="2024-04-13T21:21:44+01:00" level=debug msg="fetched chunk 8/11, size: 524288" Apr 13 21:21:50 hifi volumio[636]: verbose: New Socket.io Connection to 192.168.1.216 from 192.168.1.182 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 7 Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:50 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 21:21:50 hifi volumio[636]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 13 21:21:50 hifi volumio[636]: info: Received Get System Info Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 21:21:50 hifi volumio[636]: info: Discovery: Getting this device information Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:50 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:50 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:50 hifi volumio[636]: info: Listing playlists Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::volumioGetQueue Apr 13 21:21:50 hifi volumio[636]: info: CoreStateMachine::getQueue Apr 13 21:21:50 hifi volumio[636]: info: CorePlayQueue::getQueue Apr 13 21:21:50 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 21:21:56 hifi go-librespot[1095]: time="2024-04-13T21:21:56+01:00" level=debug msg="fetched chunk 9/11, size: 524288" Apr 13 21:21:59 hifi volumio[636]: verbose: New Socket.io Connection to 192.168.1.216 from 192.168.1.182 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 7 Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:59 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 21:21:59 hifi volumio[636]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 13 21:21:59 hifi volumio[636]: info: Received Get System Info Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 21:21:59 hifi volumio[636]: info: Discovery: Getting this device information Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:59 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::volumioGetState Apr 13 21:21:59 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:21:59 hifi volumio[636]: info: Listing playlists Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::volumioGetQueue Apr 13 21:21:59 hifi volumio[636]: info: CoreStateMachine::getQueue Apr 13 21:21:59 hifi volumio[636]: info: CorePlayQueue::getQueue Apr 13 21:21:59 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::volumioNext Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::next Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::stop Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::stPlaybackTimer Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::updateTrackBlock Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrackBlock Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:22:08 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:22:08 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::serviceStop Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 53 Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::serviceStop Apr 13 21:22:08 hifi volumio[636]: info: Spotify Stop Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: SPOTIFY STOP Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: {"status":"play","position":53,"title":"Drom Tradisie","artist":"Nicola Cruz","album":"Hybridism","albumart":"https://i.scdn.co/image/ab67616d0000b273c6933f5ad3349502f7bf300c","uri":"spotify:track:6L9tKcvLv0B8QlxNIQz9jQ","trackType":"spotify","codec":"ogg","seek":35744,"duration":138,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":67,"dbVolume":-42.5,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 13 21:22:08 hifi volumio[636]: info: Sending Spotify command to local API: /player/pause Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::play index undefined Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::startPlaybackTimer Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:08 hifi volumio[636]: info: [1713039728266] ControllerSpotify::clearAddPlayTrack Apr 13 21:22:08 hifi volumio[636]: info: Sending Spotify command with payload to local API: /player/play Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::updateTrackBlock Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrackBlock Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 66 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 67 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="pause track at 81103ms" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="fetched chunk 10/11, size: 524288" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="emitting websocket event: paused" Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6L9tKcvLv0B8QlxNIQz9jQ","play_origin":"go-librespot"}} Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::servicePushState Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:08 hifi volumio[636]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:08 hifi volumio[636]: verbose: CURRENT POSITION 54 Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::syncState stateService pause Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::syncState currentStatus stop Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:22:08 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:22:08 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="loading track spotify:track:4WKqH26BKJ9ymzRAeAbyGd (paused: false, position: 0ms)" Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 66 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 67 Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="emitting websocket event: will_play" Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","play_origin":"go-librespot"}} Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4WKqH26BKJ9ymzRAeAbyGd" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="requested aes key for file 4cc5a3f8958109afacf0b369f2a08acc671c10d8, gid: 4WKqH26BKJ9ymzRAeAbyGd" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="fetched first chunk of 15, total size is 7379368 bytes" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Apr 13 21:22:08 hifi go-librespot[1095]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Apr 13 21:22:08 hifi go-librespot[1095]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=info msg="loaded track \"Saving A Life\" (uri: spotify:track:4WKqH26BKJ9ymzRAeAbyGd, paused: false, position: 0ms, duration: 159117ms)" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="emitting websocket event: metadata" Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","name":"Saving A Life","artist_names":["Richard Houghten"],"album_name":"Sonata De Grillo","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026c095829b6e0316b95be8636","position":0,"duration":159117,"release_date":"year:2014 month:12 day:4","track_number":1,"disc_number":1}} Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="fetched chunk 2/14, size: 524288" Apr 13 21:22:08 hifi go-librespot[1095]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=trace msg="emitting websocket event: playing" Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","play_origin":"go-librespot"}} Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 21:22:08 hifi volumio[636]: SPOTIFY: {"status":"play","service":"spop","title":"Saving A Life","artist":"Richard Houghten","album":"Sonata De Grillo","albumart":"https://i.scdn.co/image/ab67616d00001e026c095829b6e0316b95be8636","uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","trackType":"spotify","seek":0,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:08 hifi volumio[636]: info: CoreCommandRouter::servicePushState Apr 13 21:22:08 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:08 hifi volumio[636]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Saving A Life","artist":"Richard Houghten","album":"Sonata De Grillo","albumart":"https://i.scdn.co/image/ab67616d00001e026c095829b6e0316b95be8636","uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","trackType":"spotify","seek":0,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:08 hifi volumio[636]: verbose: CURRENT POSITION 54 Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::syncState stateService play Apr 13 21:22:08 hifi volumio[636]: info: CoreStateMachine::syncState currentStatus stop Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="fetched chunk 3/14, size: 524288" Apr 13 21:22:08 hifi go-librespot[1095]: time="2024-04-13T21:22:08+01:00" level=debug msg="fetched chunk 1/14, size: 524288" Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: {"status":"play","service":"spop","title":"Saving A Life","artist":"Richard Houghten","album":"Sonata De Grillo","albumart":"https://i.scdn.co/image/ab67616d00001e026c095829b6e0316b95be8636","uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","trackType":"spotify","seek":0,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:09 hifi volumio[636]: info: CoreCommandRouter::servicePushState Apr 13 21:22:09 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:09 hifi volumio[636]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Saving A Life","artist":"Richard Houghten","album":"Sonata De Grillo","albumart":"https://i.scdn.co/image/ab67616d00001e026c095829b6e0316b95be8636","uri":"spotify:track:4WKqH26BKJ9ymzRAeAbyGd","trackType":"spotify","seek":0,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 21:22:09 hifi volumio[636]: verbose: CURRENT POSITION 54 Apr 13 21:22:09 hifi volumio[636]: info: CoreStateMachine::syncState stateService play Apr 13 21:22:09 hifi volumio[636]: info: CoreStateMachine::syncState currentStatus play Apr 13 21:22:09 hifi volumio[636]: info: Received an update from plugin. extracting info from payload Apr 13 21:22:09 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:22:09 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:09 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 21:22:09 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:22:09 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:22:09 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:22:09 hifi volumio[636]: info: CoreStateMachine::pushState Apr 13 21:22:09 hifi volumio[636]: info: CorePlayQueue::getTrack 54 Apr 13 21:22:09 hifi volumio[636]: info: CoreCommandRouter::volumioPushState Apr 13 21:22:09 hifi volumio[636]: info: MRS: Pushing multiroomSync output update for this device Apr 13 21:22:09 hifi volumio[636]: info: MRS: Pushing multiroomSync output Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 66 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 67 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: SPOTIFY VOLUME 66 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: VOLUMIO VOLUME 67 Apr 13 21:22:09 hifi volumio[636]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 13 21:22:17 hifi go-librespot[1095]: time="2024-04-13T21:22:17+01:00" level=debug msg="fetched chunk 4/14, size: 524288" Apr 13 21:22:25 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 21:22:25 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 21:22:29 hifi go-librespot[1095]: time="2024-04-13T21:22:29+01:00" level=debug msg="fetched chunk 5/14, size: 524288" Apr 13 21:22:40 hifi go-librespot[1095]: time="2024-04-13T21:22:40+01:00" level=debug msg="fetched chunk 6/14, size: 524288" Apr 13 21:22:52 hifi go-librespot[1095]: time="2024-04-13T21:22:52+01:00" level=debug msg="fetched chunk 7/14, size: 524288" Apr 13 21:22:56 hifi volumio[636]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mixcloud/minidspshd/buster/armhf Apr 13 21:22:58 hifi volumio[636]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mixcloud/minidspshd/buster/armhf Apr 13 21:22:58 hifi volumio[636]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMixcloud Apr 13 21:22:58 hifi volumio[636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 21:22:58 hifi volumio[636]: Cannot find translation for source SoundCloud Apr 13 21:22:58 hifi volumio[636]: Cannot find translation for source Presets Apr 13 21:22:58 hifi volumio[636]: Cannot find translation for source Spotify Apr 13 21:22:58 hifi volumio[636]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 21:22:58 hifi volumio[636]: TypeError: Cannot read property 'clear' of undefined Apr 13 21:22:58 hifi volumio[636]: at MixcloudContext.reset (/data/plugins/music_service/mixcloud/lib/mixcloud.js:86:21) Apr 13 21:22:58 hifi volumio[636]: at ControllerMixcloud.onStop (/data/plugins/music_service/mixcloud/index.js:140:14) Apr 13 21:22:58 hifi volumio[636]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 13 21:22:58 hifi volumio[636]: at /volumio/app/pluginmanager.js:956:12 Apr 13 21:22:58 hifi volumio[636]: at ChildProcess.exithandler (child_process.js:374:7) Apr 13 21:22:58 hifi volumio[636]: at ChildProcess.emit (events.js:400:28) Apr 13 21:22:58 hifi volumio[636]: at maybeClose (internal/child_process.js:1088:16) Apr 13 21:22:58 hifi volumio[636]: at Socket. (internal/child_process.js:446:11) Apr 13 21:22:58 hifi volumio[636]: at Socket.emit (events.js:400:28) Apr 13 21:22:58 hifi volumio[636]: at Pipe. (net.js:686:12) Apr 13 21:22:58 hifi volumio[636]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 21:22:59 hifi sudo[23353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-13 21:21 Apr 13 21:22:59 hifi sudo[23353]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"