-- Logs begin at Mon 2025-04-21 11:09:41 -03, end at Wed 2025-04-23 10:42:45 -03. -- Apr 23 10:41:04 volumio go-librespot[1200]: time="2025-04-23T10:41:04-03:00" level=trace msg="received accesspoint ping" Apr 23 10:41:05 volumio go-librespot[1200]: time="2025-04-23T10:41:05-03:00" level=trace msg="received accesspoint pong ack" Apr 23 10:41:05 volumio go-librespot[1200]: time="2025-04-23T10:41:05-03:00" level=trace msg="sent dealer ping" Apr 23 10:41:05 volumio go-librespot[1200]: time="2025-04-23T10:41:05-03:00" level=trace msg="received dealer pong" Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/478e8b2cb57f6b006b73e767-1686076209.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Taj Mahal - Sweet Home Chicago (Background Vocals: The Pointer Sisters)","title":"Sweet Home Chicago (Background Vocals: The Pointer Sisters)","artist":"Taj Mahal","album":"Recycling The Blues & Other Related Stuff","streaming":true,"disableUiControls":true,"duration":13,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:29 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:29 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:29 volumio volumio[801]: info: [1745415689233] [MotherEarth] setting new timer with duration of 13000 seconds. Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/478e8b2cb57f6b006b73e767-1686076209.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Taj Mahal - Sweet Home Chicago (Background Vocals: The Pointer Sisters)","title":"Sweet Home Chicago (Background Vocals: The Pointer Sisters)","artist":"Taj Mahal","album":"Recycling The Blues & Other Related Stuff","streaming":true,"disableUiControls":true,"duration":13,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:29 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:29 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:29 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:29 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:29 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:29 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:29 volumio volumio[801]: info: [1745415689386] [MotherEarth] setting new timer with duration of 13000 seconds. Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:29 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/478e8b2cb57f6b006b73e767-1686076209.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Taj Mahal - Sweet Home Chicago (Background Vocals: The Pointer Sisters)","title":"Sweet Home Chicago (Background Vocals: The Pointer Sisters)","artist":"Taj Mahal","album":"Recycling The Blues & Other Related Stuff","streaming":true,"disableUiControls":true,"duration":13,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:30 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:30 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:30 volumio volumio[801]: info: [1745415690120] [MotherEarth] setting new timer with duration of 13000 seconds. Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/926e3764d2874b419f9dfc7d-1702146522.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - Pull The Catch","title":"Pull The Catch","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":612,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:30 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:30 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:30 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:30 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:30 volumio volumio[801]: info: [1745415690336] [MotherEarth] setting new timer with duration of 612000 seconds. Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:30 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:30 volumio volumio[801]: info: CorePlayQueue::getTrack 1 Apr 23 10:41:35 volumio go-librespot[1200]: time="2025-04-23T10:41:35-03:00" level=trace msg="sent dealer ping" Apr 23 10:41:35 volumio go-librespot[1200]: time="2025-04-23T10:41:35-03:00" level=trace msg="received dealer pong" Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/926e3764d2874b419f9dfc7d-1702146522.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - Pull The Catch","title":"Pull The Catch","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":612,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:43 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:43 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:43 volumio volumio[801]: info: [1745415703416] [MotherEarth] setting new timer with duration of 612000 seconds. Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 1 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/926e3764d2874b419f9dfc7d-1702146522.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - Pull The Catch","title":"Pull The Catch","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":612,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:43 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:43 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:43 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:43 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:43 volumio volumio[801]: info: [1745415703583] [MotherEarth] setting new timer with duration of 612000 seconds. Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:43 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:43 volumio volumio[801]: info: CorePlayQueue::getTrack 1 Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:44 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:44 volumio volumio[801]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/926e3764d2874b419f9dfc7d-1702146522.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - Pull The Catch","title":"Pull The Catch","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":612,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:41:44 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:41:44 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 23 10:41:44 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 23 10:41:44 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 23 10:41:44 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:44 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:44 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:44 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:44 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:44 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:41:44 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:41:44 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:41:44 volumio volumio[801]: info: [1745415704351] [MotherEarth] setting new timer with duration of 612000 seconds. Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:41:44 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:41:44 volumio volumio[801]: info: CorePlayQueue::getTrack 1 Apr 23 10:42:05 volumio go-librespot[1200]: time="2025-04-23T10:42:05-03:00" level=trace msg="sent dealer ping" Apr 23 10:42:05 volumio go-librespot[1200]: time="2025-04-23T10:42:05-03:00" level=trace msg="received dealer pong" Apr 23 10:42:35 volumio go-librespot[1200]: time="2025-04-23T10:42:35-03:00" level=trace msg="sent dealer ping" Apr 23 10:42:35 volumio go-librespot[1200]: time="2025-04-23T10:42:35-03:00" level=trace msg="received dealer pong" Apr 23 10:42:37 volumio volumio[801]: ------------------------------------ BT MESSAGE: BT STATUS: pending Apr 23 10:42:37 volumio volumio[801]: ------------------------------------ BT MESSAGE: BT STATUS: active Apr 23 10:42:37 volumio volumio[801]: BT PLUGIN MESSAGE: Playing: true Apr 23 10:42:37 volumio volumio[801]: BT PLUGIN MESSAGE: VOLATILE: undefined Apr 23 10:42:37 volumio volumio[801]: BT PLUGIN MESSAGE: STATE:undefined Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioStop Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::stop Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::stPlaybackTimer Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::updateTrackBlock Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrackBlock Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio pulseaudio[1540]: Cannot set requested source latency of 66.67 ms, adjusting to 135.29 ms Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::serviceStop Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::serviceStop Apr 23 10:42:37 volumio volumio[801]: info: ControllerMpd::stop Apr 23 10:42:37 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:42:37 volumio volumio[801]: info: Apr 23 10:42:37 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 23 10:42:37 volumio volumio[801]: info: sendMpdCommand stop took 31 milliseconds Apr 23 10:42:37 volumio volumio[801]: info: ControllerMpd::getState Apr 23 10:42:37 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/926e3764d2874b419f9dfc7d-1702146522.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - Pull The Catch","title":"Pull The Catch","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":612,"seek":0,"bitdepth":"24 bit","channels":2} Apr 23 10:42:37 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::syncState stateService stop Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus stop Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:42:37 volumio volumio[801]: info: No code Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:42:37 volumio volumio[801]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Apr 23 10:42:37 volumio volumio[801]: info: Enabling Bluetooth Audio Output Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:42:37 volumio volumio[801]: info: sendMpdCommand status took 131 milliseconds Apr 23 10:42:37 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 23 10:42:37 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 10:42:37 volumio volumio[801]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 23 10:42:37 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 23 10:42:37 volumio volumio[801]: info: ControllerMpd::pushState Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 23 10:42:37 volumio volumio[801]: info: CoreStateMachine::pushState Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 10:42:37 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 23 10:42:37 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 23 10:42:37 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 23 10:42:37 volumio volumio[801]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 23 10:42:37 volumio volumio[801]: info: ------------------------------ 162ms Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: SPOTIFY VOLUME 99 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: VOLUMIO VOLUME 100 Apr 23 10:42:37 volumio volumio[801]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 23 10:42:37 volumio volumio[801]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 23 10:42:37 volumio volumio[801]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 23 10:42:37 volumio volumio[801]: info: camilladsp service terminated, instance 1 Apr 23 10:42:37 volumio volumio[801]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 23 10:42:37 volumio volumio[801]: info: camilladsp spawned new process with pid 11532, instance 1, run: true Apr 23 10:42:37 volumio volumio[801]: info: camilladsp service started and running in background, instance 1 Apr 23 10:42:37 volumio pulseaudio[1540]: Too many underruns, increasing latency to 205.00 ms Apr 23 10:42:37 volumio pulseaudio[1540]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Apr 23 10:42:37 volumio pulseaudio[1540]: Cannot set requested sink latency of 337.51 ms, adjusting to 600.00 ms Apr 23 10:42:38 volumio volumio[801]: info: Bluetooth Audio successfully Enabled Apr 23 10:42:38 volumio pulseaudio[1540]: Too many underruns, increasing latency to 815.32 ms Apr 23 10:42:44 volumio volumio[801]: info: [1745415764499] [80s80s] Pushing the next song state Richard Sanderson - Reality and getting next track. Apr 23 10:42:44 volumio volumio[801]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 10:42:44 volumio volumio[801]: TypeError: Cannot set property 'name' of undefined Apr 23 10:42:44 volumio volumio[801]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 23 10:42:44 volumio volumio[801]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 23 10:42:44 volumio volumio[801]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 23 10:42:44 volumio volumio[801]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 23 10:42:44 volumio volumio[801]: at processImmediate (internal/timers.js:461:21) Apr 23 10:42:44 volumio volumio[801]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 10:42:45 volumio sudo[11566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 10:41 Apr 23 10:42:45 volumio sudo[11566]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"