-- Logs begin at Wed 2025-04-23 10:46:34 -03, end at Mon 2025-04-28 11:06:38 -03. -- Apr 28 11:05:08 volumio go-librespot[1202]: time="2025-04-28T11:05:08-03:00" level=trace msg="sent dealer ping" Apr 28 11:05:09 volumio go-librespot[1202]: time="2025-04-28T11:05:09-03:00" level=trace msg="received dealer pong" Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:05:36 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:05:36 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/53fa0c88c1d6898659c013f4-1708296867.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental","name":"Faith No More - King For A Day, Fool For A Lifetime","title":"King For A Day, Fool For A Lifetime","artist":"Faith No More","album":"King For A Day, Fool For A Lifetime","streaming":true,"disableUiControls":true,"duration":379,"seek":0,"bitdepth":"24 bit","channels":2} Apr 28 11:05:36 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 28 11:05:36 volumio volumio[801]: info: CoreStateMachine::syncState stateService play Apr 28 11:05:36 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus play Apr 28 11:05:36 volumio volumio[801]: info: Received an update from plugin. extracting info from payload Apr 28 11:05:36 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:05:36 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:05:36 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:05:36 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:05:36 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:05:36 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:05:36 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:05:36 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:05:36 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:05:36 volumio volumio[801]: info: [1745849136476] [MotherEarth] setting new timer with duration of 379000 seconds. Apr 28 11:05:36 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:05:36 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:05:36 volumio volumio[801]: info: CorePlayQueue::getTrack 1 Apr 28 11:05:38 volumio go-librespot[1202]: time="2025-04-28T11:05:38-03:00" level=trace msg="sent dealer ping" Apr 28 11:05:39 volumio go-librespot[1202]: time="2025-04-28T11:05:39-03:00" level=trace msg="received dealer pong" Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::volumioPause Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::pause Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::stPlaybackTimer Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::servicePause Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::servicePause Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand pause Apr 28 11:06:04 volumio volumio[801]: info: Apr 28 11:06:04 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand pause took 6 milliseconds Apr 28 11:06:04 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 volumio volumio[801]: verbose: STATE SERVICE {"status":"pause","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/53fa0c88c1d6898659c013f4-1708296867.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental","name":"Faith No More - King For A Day, Fool For A Lifetime","title":"King For A Day, Fool For A Lifetime","artist":"Faith No More","album":"King For A Day, Fool For A Lifetime","streaming":true,"disableUiControls":true,"duration":379,"seek":27365,"bitdepth":"24 bit","channels":2} Apr 28 11:06:04 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::syncState stateService pause Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus pause Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:04 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:04 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:04 volumio volumio[801]: info: CoreStateMachine::stPlaybackTimer Apr 28 11:06:04 volumio volumio[801]: info: Apr 28 11:06:04 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:04 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:04 volumio volumio[801]: info: Apr 28 11:06:04 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:04 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand status took 35 milliseconds Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand status took 6 milliseconds Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand status took 4 milliseconds Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:04 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 28 11:06:04 volumio volumio[801]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:04 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:04 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 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 28 11:06:04 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 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 28 11:06:04 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:04 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:04 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:04 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 28 11:06:04 volumio volumio[801]: info: ------------------------------ 64ms Apr 28 11:06:04 volumio volumio[801]: info: ------------------------------ 34ms Apr 28 11:06:04 volumio volumio[801]: info: ------------------------------ 31ms Apr 28 11:06:04 volumio volumio[801]: info: camilladsp spawned new process with pid 32583, instance 1, run: true Apr 28 11:06:08 volumio go-librespot[1202]: time="2025-04-28T11:06:08-03:00" level=trace msg="sent dealer ping" Apr 28 11:06:09 volumio go-librespot[1202]: time="2025-04-28T11:06:09-03:00" level=trace msg="received dealer pong" Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioGetState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioGetState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: Currently active: motherearthradio Apr 28 11:06:23 volumio volumio[801]: info: Stopping currently active service Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioStop Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::stop Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::updateTrackBlock Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrackBlock Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::stPlaybackTimer Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::serviceStop Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::serviceStop Apr 28 11:06:23 volumio volumio[801]: info: ControllerMpd::stop Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: info: Apr 28 11:06:23 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand stop took 40 milliseconds Apr 28 11:06:23 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 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/53fa0c88c1d6898659c013f4-1708296867.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental","name":"Faith No More - King For A Day, Fool For A Lifetime","title":"King For A Day, Fool For A Lifetime","artist":"Faith No More","album":"King For A Day, Fool For A Lifetime","streaming":true,"disableUiControls":true,"duration":379,"seek":27365,"bitdepth":"24 bit","channels":2} Apr 28 11:06:23 volumio volumio[801]: verbose: CURRENT POSITION 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::syncState stateService stop Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::syncState currentStatus stop Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: No code Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: TidalConnect Active Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioGetState Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: Currently active: motherearthradio Apr 28 11:06:23 volumio volumio[801]: info: Setting Volatile state to tidalconnect Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received tidalconnect Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 volumio volumio[801]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received tidalconnect Apr 28 11:06:23 volumio volumio[801]: info: Apr 28 11:06:23 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:23 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:23 volumio volumio[801]: info: Apr 28 11:06:23 volumio volumio[801]: ---------------------------- MPD announces state update: player Apr 28 11:06:23 volumio volumio[801]: info: ControllerMpd::getState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand status Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand status took 78 milliseconds Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand status took 5 milliseconds Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand status took 3 milliseconds Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseState Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 28 11:06:23 volumio volumio[801]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:23 volumio volumio[801]: verbose: ControllerMpd::parseTrackInfo Apr 28 11:06:23 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 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 28 11:06:23 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 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 28 11:06:23 volumio volumio[801]: info: ControllerMpd::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:23 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:23 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:23 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:23 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:23 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 28 11:06:23 volumio volumio[801]: info: ------------------------------ 175ms Apr 28 11:06:23 volumio volumio[801]: info: ------------------------------ 102ms Apr 28 11:06:23 volumio volumio[801]: info: ------------------------------ 100ms Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:23 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:23 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:23 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:24 volumio vtcs[1648]: [2025-04-28 11:06:24.523] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Apr 28 11:06:24 volumio vtcs[1648]: [2025-04-28 11:06:24.524] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Apr 28 11:06:24 volumio volumio[801]: info: Pushing metadata Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:24 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:24 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:24 volumio volumio[801]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received tidalconnect Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:24 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:24 volumio volumio[801]: info: CorePlayQueue::getTrack 0 Apr 28 11:06:24 volumio volumio[801]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received tidalconnect Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:24 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:24 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:24 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:24 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:24 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:24 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:24 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:24 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:24 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:24 volumio volumio[801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.lHoh5s4r4KXrweQahAvdWC5BBk33.95d071dca8379fc9cf0e8fc2110e9961.state.status' Apr 28 11:06:25 volumio volumio[801]: info: Pushing metadata Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:25 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:25 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:25 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:25 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:25 volumio volumio[801]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 28 11:06:25 volumio volumio[801]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 28 11:06:25 volumio volumio[801]: info: camilladsp stopping service pid 32583... Apr 28 11:06:25 volumio volumio[801]: info: camilladsp service terminated, instance 1 Apr 28 11:06:25 volumio volumio[801]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 28 11:06:25 volumio volumio[801]: info: camilladsp service started and running in background, instance 1 Apr 28 11:06:25 volumio volumio[801]: info: camilladsp spawned new process with pid 32716, instance 1, run: true Apr 28 11:06:26 volumio vtcs[1648]: [2025-04-28 11:06:26.039] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Apr 28 11:06:26 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:26 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:26 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:26 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:26 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:26 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:26 volumio volumio[801]: info: CoreCommandRouter::servicePushState Apr 28 11:06:26 volumio volumio[801]: info: CoreStateMachine::pushState Apr 28 11:06:26 volumio volumio[801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:06:26 volumio volumio[801]: info: CoreCommandRouter::volumioPushState Apr 28 11:06:26 volumio volumio[801]: info: MRS: Pushing multiroomSync output update for this device Apr 28 11:06:26 volumio volumio[801]: info: MRS: Pushing multiroomSync output Apr 28 11:06:26 volumio volumio[801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 11:06:37 volumio volumio[801]: info: [1745849197150] [80s80s] Pushing the next song state Eurythmics - Who's That Girl? and getting next track. Apr 28 11:06:37 volumio volumio[801]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 11:06:37 volumio volumio[801]: TypeError: Cannot set property 'name' of undefined Apr 28 11:06:37 volumio volumio[801]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 28 11:06:37 volumio volumio[801]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 28 11:06:37 volumio volumio[801]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 28 11:06:37 volumio volumio[801]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 28 11:06:37 volumio volumio[801]: at processImmediate (internal/timers.js:461:21) Apr 28 11:06:37 volumio volumio[801]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 11:06:38 volumio sudo[304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 11:05 Apr 28 11:06:38 volumio sudo[304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:06:38 volumio go-librespot[1202]: time="2025-04-28T11:06:38-03:00" level=trace msg="received accesspoint ping" 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"