-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-19 16:30:55 CET. -- Jan 19 16:29:00 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:00+01:00" level=debug msg="fetched chunk 10/18, size: 524288" Jan 19 16:29:00 volumio-euro volumiologrotate[604]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 19 16:29:00 volumio-euro volumiologrotate[604]: ls: cannot access 'EURO': No such file or directory Jan 19 16:29:11 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:11+01:00" level=debug msg="fetched chunk 11/18, size: 524288" Jan 19 16:29:22 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:22+01:00" level=debug msg="fetched chunk 12/18, size: 524288" Jan 19 16:29:26 volumio-euro volumio[1003]: Background upload size exceeds 3 MB, aborting Jan 19 16:29:26 volumio-euro volumio[1003]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 19 16:29:26 volumio-euro volumio[1003]: info: CALLMETHOD: miscellanea appearance sendSizeErrorToasMessage 3 Jan 19 16:29:26 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , sendSizeErrorToasMessage Jan 19 16:29:33 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:33+01:00" level=debug msg="fetched chunk 13/18, size: 524288" Jan 19 16:29:43 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:43+01:00" level=debug msg="fetched chunk 14/18, size: 524288" Jan 19 16:29:50 volumio-euro volumio[1003]: Background upload size exceeds 3 MB, aborting Jan 19 16:29:50 volumio-euro volumio[1003]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 19 16:29:50 volumio-euro volumio[1003]: info: CALLMETHOD: miscellanea appearance sendSizeErrorToasMessage 3 Jan 19 16:29:50 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , sendSizeErrorToasMessage Jan 19 16:29:55 volumio-euro go-librespot[1275]: time="2025-01-19T16:29:55+01:00" level=debug msg="fetched chunk 15/18, size: 524288" Jan 19 16:30:01 volumio-euro volumiologrotate[604]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 19 16:30:01 volumio-euro volumiologrotate[604]: ls: cannot access 'EURO': No such file or directory Jan 19 16:30:02 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds Jan 19 16:30:02 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 19 16:30:02 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 16:30:06 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:06+01:00" level=debug msg="fetched chunk 16/18, size: 524288" Jan 19 16:30:06 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds Jan 19 16:30:06 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 19 16:30:06 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 16:30:14 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume45 Jan 19 16:30:14 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:14 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:14 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:14 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:14 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:14 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:14 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 19 16:30:14 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 41 Jan 19 16:30:14 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 45 Jan 19 16:30:14 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:14 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 45 Jan 19 16:30:15 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume55 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 45 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 55 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:15 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 55 Jan 19 16:30:15 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume60 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 55 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 60 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:15 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 60 Jan 19 16:30:15 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume65 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 60 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 65 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:15 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 65 Jan 19 16:30:15 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume70 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:15 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:15 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 65 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 70 Jan 19 16:30:15 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:15 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 70 Jan 19 16:30:16 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume70 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jan 19 16:30:16 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume50 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 70 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 50 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:16 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 50 Jan 19 16:30:16 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume35 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 50 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 35 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:16 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 35 Jan 19 16:30:16 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume25 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:16 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:16 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 35 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 25 Jan 19 16:30:16 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:16 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 25 Jan 19 16:30:16 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume15 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 25 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 15 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:17 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 15 Jan 19 16:30:17 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:17+01:00" level=debug msg="fetched chunk 17/18, size: 524288" Jan 19 16:30:17 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume10 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 15 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 10 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:17 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 10 Jan 19 16:30:17 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume5 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:17 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:17 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 10 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 5 Jan 19 16:30:17 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:17 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 5 Jan 19 16:30:18 volumio-euro volumio[1003]: info: VolumeController::SetAlsaVolume15 Jan 19 16:30:18 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:18 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:18 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:18 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:18 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:18 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:18 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:18 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY VOLUME 5 Jan 19 16:30:18 volumio-euro volumio[1003]: SPOTIFY: VOLUMIO VOLUME 15 Jan 19 16:30:18 volumio-euro volumio[1003]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 19 16:30:18 volumio-euro volumio[1003]: info: Setting Spotify Volume from Volumio: 15 Jan 19 16:30:20 volumio-euro volumio[1003]: SPOTIFY: SETTING SPOTIFY VOLUME 15 Jan 19 16:30:20 volumio-euro volumio[1003]: info: Sending Spotify command with payload to local API: /player/volume Jan 19 16:30:20 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:20+01:00" level=debug msg="update volume to 9830/65535" Jan 19 16:30:20 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:20+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 19 16:30:20 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:20+01:00" level=trace msg="emitting websocket event: volume" Jan 19 16:30:20 volumio-euro volumio[1003]: SPOTIFY: received: {"type":"volume","data":{"value":15,"max":100}} Jan 19 16:30:20 volumio-euro volumio[1003]: SPOTIFY: RECEIVED SPOTIFY VOLUME 15 Jan 19 16:30:27 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:27+01:00" level=debug msg="fetched chunk 18/18, size: 260068" Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 19 16:30:41 volumio-euro volumio[1003]: info: Pausing Remote Device: http://192.168.2.44 Jan 19 16:30:41 volumio-euro volumio[1003]: info: Jan 19 16:30:41 volumio-euro volumio[1003]: ---------------------------- Client requests Volumio pause Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPause Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreStateMachine::pause Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreStateMachine::stPlaybackTimer Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreStateMachine::servicePause Jan 19 16:30:41 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:41 volumio-euro volumio[1003]: info: CoreCommandRouter::servicePause Jan 19 16:30:41 volumio-euro volumio[1003]: info: Spotify Received pause Jan 19 16:30:41 volumio-euro volumio[1003]: SPOTIFY: SPOTIFY PAUSE Jan 19 16:30:41 volumio-euro volumio[1003]: SPOTIFY: {"status":"play","position":83,"title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d0000b273ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","codec":"ogg","seek":149535,"duration":206,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":15,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 19 16:30:41 volumio-euro volumio[1003]: info: Sending Spotify command to local API: /player/pause Jan 19 16:30:41 volumio-euro volumio[1003]: info: Done pausing: Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=debug msg="pause track at 175871ms" Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=trace msg="emitting websocket event: paused" Jan 19 16:30:42 volumio-euro volumio[1003]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2oYheX6lx92jmxzainoy6l","play_origin":"go-librespot"}} Jan 19 16:30:42 volumio-euro volumio[1003]: SPOTIFY: PUSH STATE SPOTIFY Jan 19 16:30:42 volumio-euro volumio[1003]: SPOTIFY: {"status":"pause","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreCommandRouter::servicePushState Jan 19 16:30:42 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:42 volumio-euro volumio[1003]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:42 volumio-euro volumio[1003]: verbose: CURRENT POSITION 83 Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::syncState stateService pause Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::syncState currentStatus pause Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:42 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:42 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:42 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::stPlaybackTimer Jan 19 16:30:42 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:42 volumio-euro volumio[1003]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 19 16:30:42 volumio-euro volumio[1003]: info: Playing Remote Device: http://192.168.2.44 Jan 19 16:30:42 volumio-euro volumio[1003]: info: Jan 19 16:30:42 volumio-euro volumio[1003]: ---------------------------- Client requests Volumio play Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPlay Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::play index undefined Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 16:30:42 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:42 volumio-euro volumio[1003]: info: CoreStateMachine::startPlaybackTimer Jan 19 16:30:42 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:42 volumio-euro volumio[1003]: info: Spotify Resume Jan 19 16:30:42 volumio-euro volumio[1003]: info: Sending Spotify command to local API: /player/resume Jan 19 16:30:42 volumio-euro volumio[1003]: info: Done playing: Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=trace msg="seek to 175871ms (diff: 188ms, samples: 7755911, bytes: 8376734)" Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=debug msg="resume track at 175683ms" Jan 19 16:30:42 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 16:30:43 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 16:30:43 volumio-euro go-librespot[1275]: time="2025-01-19T16:30:43+01:00" level=trace msg="emitting websocket event: playing" Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2oYheX6lx92jmxzainoy6l","play_origin":"go-librespot"}} Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: PUSH STATE SPOTIFY Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: {"status":"play","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::servicePushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:43 volumio-euro volumio[1003]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:43 volumio-euro volumio[1003]: verbose: CURRENT POSITION 83 Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::syncState stateService play Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::syncState currentStatus pause Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: PUSH STATE SPOTIFY Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: {"status":"play","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::servicePushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:43 volumio-euro volumio[1003]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kali","artist":"Django Wagner","album":"Kali","albumart":"https://i.scdn.co/image/ab67616d00001e02ec51bacd9d9d4622b3569c42","uri":"spotify:track:2oYheX6lx92jmxzainoy6l","trackType":"spotify","seek":170000,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 19 16:30:43 volumio-euro volumio[1003]: verbose: CURRENT POSITION 83 Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::syncState stateService play Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::syncState currentStatus play Jan 19 16:30:43 volumio-euro volumio[1003]: info: Received an update from plugin. extracting info from payload Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreStateMachine::pushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: CorePlayQueue::getTrack 83 Jan 19 16:30:43 volumio-euro volumio[1003]: info: CoreCommandRouter::volumioPushState Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output update for this device Jan 19 16:30:43 volumio-euro volumio[1003]: info: MRS: Pushing multiroomSync output Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:43 volumio-euro volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jan 19 16:30:55 volumio-euro volumio[1003]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jan 19 16:30:55 volumio-euro volumio[1003]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jan 19 16:30:55 volumio-euro volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 16:30:55 volumio-euro volumio[1003]: TypeError: Cannot read property 'then' of undefined Jan 19 16:30:55 volumio-euro volumio[1003]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jan 19 16:30:55 volumio-euro volumio[1003]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2273:30) Jan 19 16:30:55 volumio-euro volumio[1003]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Jan 19 16:30:55 volumio-euro volumio[1003]: at Socket.emit (events.js:315:20) Jan 19 16:30:55 volumio-euro volumio[1003]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 19 16:30:55 volumio-euro volumio[1003]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 19 16:30:55 volumio-euro volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 16:30:55 volumio-euro sudo[5930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 16:29 Jan 19 16:30:55 volumio-euro sudo[5930]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"