-- 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"