-- Logs begin at Thu 2024-08-29 17:43:33 CEST, end at Thu 2024-08-29 17:48:43 CEST. -- Aug 29 17:47:16 volumio volumio[1065]: info: CorePlayQueue::getTrack 1 Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , goto Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:16 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:16 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 17:47:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 17:47:19 volumio volumio[1065]: info: Discovery: Getting this device information Aug 29 17:47:19 volumio volumio[1065]: info: CoreCommandRouter::volumioGetState Aug 29 17:47:19 volumio volumio[1065]: info: CorePlayQueue::getTrack 1 Aug 29 17:47:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:23 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:28 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:28 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:35 volumio volumio[1065]: error: Failed request for metavolumio API Aug 29 17:47:37 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:37 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:39 volumio volumio[1065]: info: Executing endpoint metavolumio Aug 29 17:47:39 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 17:47:49 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 17:47:49 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 17:47:49 volumio volumio[1065]: info: Discovery: Getting this device information Aug 29 17:47:49 volumio volumio[1065]: info: CoreCommandRouter::volumioGetState Aug 29 17:47:49 volumio volumio[1065]: info: CorePlayQueue::getTrack 1 Aug 29 17:47:49 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioNext Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::next Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::stop Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::updateTrackBlock Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrackBlock Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 1 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::serviceStop Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 1 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::serviceStop Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::stop Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:47:55 volumio volumio[1065]: info: Aug 29 17:47:55 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand stop took 11 milliseconds Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::play index undefined Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::updateTrackBlock Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrackBlock Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand status took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand stop took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 17:47:55 volumio volumio[1065]: info: Aug 29 17:47:55 volumio volumio[1065]: ---------------------------- MPD announces system playlist update Aug 29 17:47:55 volumio volumio[1065]: info: Ignoring MPD Status Update Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand clear took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac" Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Brujo","artist":"Johannes Luley","album":"Follow Your Heart","uri":"INTERNAL/HI-Res Audio/Johannes Luley - Follow Your Heart (2021) [24-96]/02 - Brujo.flac","trackType":"flac"} Aug 29 17:47:55 volumio volumio[1065]: verbose: CURRENT POSITION 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState stateService stop Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: No code Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: ------------------------------ 12ms Aug 29 17:47:55 volumio volumio[1065]: error: updateQueue error: null Aug 29 17:47:55 volumio volumio[1065]: info: Aug 29 17:47:55 volumio volumio[1065]: ---------------------------- MPD announces system playlist update Aug 29 17:47:55 volumio volumio[1065]: info: Ignoring MPD Status Update Aug 29 17:47:55 volumio volumio[1065]: info: ------------------------------ 11ms Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand add "INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac" took 11 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand play Aug 29 17:47:55 volumio volumio[1065]: info: Aug 29 17:47:55 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:47:55 volumio volumio[1065]: info: ------------------------------ 6ms Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand play took 5 milliseconds Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:47:55 volumio volumio[1065]: info: Aug 29 17:47:55 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand status took 1 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand status took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cello Concerto in A Minor, RV. 419: I. Allegro","artist":"Ophélie Gaillard & Pulcinella Orchestra","album":"Vivaldi: I colori dell'ombra","uri":"INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac","trackType":"flac"} Aug 29 17:47:55 volumio volumio[1065]: verbose: CURRENT POSITION 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState stateService play Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:47:55 volumio volumio[1065]: info: ------------------------------ 2ms Aug 29 17:47:55 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 17:47:55 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:47:55 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cello Concerto in A Minor, RV. 419: I. Allegro","artist":"Ophélie Gaillard & Pulcinella Orchestra","album":"Vivaldi: I colori dell'ombra","uri":"INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac","trackType":"flac"} Aug 29 17:47:55 volumio volumio[1065]: verbose: CURRENT POSITION 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState stateService play Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:47:55 volumio volumio[1065]: info: Received an update from plugin. extracting info from payload Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:47:55 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:47:55 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:47:55 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:47:55 volumio volumio[1065]: info: ------------------------------ 10ms Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioNext Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::next Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::stop Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::stPlaybackTimer Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::updateTrackBlock Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrackBlock Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::serviceStop Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 2 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::serviceStop Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::stop Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:48:18 volumio volumio[1065]: info: Aug 29 17:48:18 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand stop took 13 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::play index undefined Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::startPlaybackTimer Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/HI-Res Audio/Elvis Presley/Elvis Presley - The Complete '70s Albums Collection [24-96]/Disc 04 - That's The Way It Is (1970)/05. Mary In The Morning.flac Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::updateTrackBlock Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrackBlock Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand status took 2 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand stop took 2 milliseconds Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 17:48:18 volumio volumio[1065]: info: Aug 29 17:48:18 volumio volumio[1065]: ---------------------------- MPD announces system playlist update Aug 29 17:48:18 volumio volumio[1065]: info: Ignoring MPD Status Update Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand clear took 1 milliseconds Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/HI-Res Audio/Elvis Presley/Elvis Presley - The Complete '70s Albums Collection [24-96]/Disc 04 - That's The Way It Is (1970)/05. Mary In The Morning.flac" Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cello Concerto in A Minor, RV. 419: I. Allegro","artist":"Ophélie Gaillard & Pulcinella Orchestra","album":"Vivaldi: I colori dell'ombra","uri":"INTERNAL/HI-Res Audio/Ophelie Gaillard/Ophelie Gaillard, Pulcinella Orchestra - Vivaldi - I colori dell’ombra (2020) [24-96]/28. Cello Concerto in A Minor, RV. 419 I. Allegro.flac","trackType":"flac"} Aug 29 17:48:18 volumio volumio[1065]: verbose: CURRENT POSITION 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState stateService stop Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: No code Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: ------------------------------ 15ms Aug 29 17:48:18 volumio volumio[1065]: error: updateQueue error: null Aug 29 17:48:18 volumio volumio[1065]: info: Aug 29 17:48:18 volumio volumio[1065]: ---------------------------- MPD announces system playlist update Aug 29 17:48:18 volumio volumio[1065]: info: Ignoring MPD Status Update Aug 29 17:48:18 volumio volumio[1065]: info: ------------------------------ 12ms Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand add "INTERNAL/HI-Res Audio/Elvis Presley/Elvis Presley - The Complete '70s Albums Collection [24-96]/Disc 04 - That's The Way It Is (1970)/05. Mary In The Morning.flac" took 11 milliseconds Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand play Aug 29 17:48:18 volumio volumio[1065]: info: Aug 29 17:48:18 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:48:18 volumio volumio[1065]: info: ------------------------------ 5ms Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand play took 4 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:48:18 volumio volumio[1065]: info: Aug 29 17:48:18 volumio volumio[1065]: ---------------------------- MPD announces state update: player Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::getState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand status took 1 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand status took 1 milliseconds Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseState Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 17:48:18 volumio volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:48:18 volumio volumio[1065]: verbose: ControllerMpd::parseTrackInfo Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mary In The Morning","artist":"Elvis Presley","album":"That's The Way It Is","uri":"INTERNAL/HI-Res Audio/Elvis Presley/Elvis Presley - The Complete '70s Albums Collection [24-96]/Disc 04 - That's The Way It Is (1970)/05. Mary In The Morning.flac","trackType":"flac"} Aug 29 17:48:18 volumio volumio[1065]: verbose: CURRENT POSITION 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState stateService play Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Aug 29 17:48:18 volumio volumio[1065]: info: ControllerMpd::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::servicePushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mary In The Morning","artist":"Elvis Presley","album":"That's The Way It Is","uri":"INTERNAL/HI-Res Audio/Elvis Presley/Elvis Presley - The Complete '70s Albums Collection [24-96]/Disc 04 - That's The Way It Is (1970)/05. Mary In The Morning.flac","trackType":"flac"} Aug 29 17:48:18 volumio volumio[1065]: verbose: CURRENT POSITION 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState stateService play Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::syncState currentStatus play Aug 29 17:48:18 volumio volumio[1065]: info: Received an update from plugin. extracting info from payload Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: CoreStateMachine::pushState Aug 29 17:48:18 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:18 volumio volumio[1065]: info: CoreCommandRouter::volumioPushState Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Aug 29 17:48:18 volumio volumio[1065]: info: MRS: Pushing multiroomSync output Aug 29 17:48:18 volumio volumio[1065]: info: ------------------------------ 5ms Aug 29 17:48:18 volumio volumio[1065]: info: ------------------------------ 9ms Aug 29 17:48:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 17:48:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 17:48:19 volumio volumio[1065]: info: Discovery: Getting this device information Aug 29 17:48:19 volumio volumio[1065]: info: CoreCommandRouter::volumioGetState Aug 29 17:48:19 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:19 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 17:48:32 volumio volumio[1065]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 17:48:32 volumio volumio[1065]: info: CoreCommandRouter::volumioGetState Aug 29 17:48:32 volumio volumio[1065]: info: CorePlayQueue::getTrack 3 Aug 29 17:48:32 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 17:48:32 volumio volumio[1065]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 17:48:32 volumio volumio[1065]: info: Listing playlists Aug 29 17:48:32 volumio volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 17:48:32 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 17:48:32 volumio volumio[1065]: info: CoreCommandRouter::volumioGetQueue Aug 29 17:48:32 volumio volumio[1065]: info: CoreStateMachine::getQueue Aug 29 17:48:32 volumio volumio[1065]: info: CorePlayQueue::getQueue Aug 29 17:48:43 volumio volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Aug 29 17:48:43 volumio volumio[1065]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Aug 29 17:48:43 volumio volumio[1065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 17:48:43 volumio volumio[1065]: TypeError: Cannot read property 'then' of undefined Aug 29 17:48:43 volumio volumio[1065]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Aug 29 17:48:43 volumio volumio[1065]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Aug 29 17:48:43 volumio volumio[1065]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Aug 29 17:48:43 volumio volumio[1065]: at Socket.emit (events.js:400:28) Aug 29 17:48:43 volumio volumio[1065]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 17:48:43 volumio volumio[1065]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 29 17:48:43 volumio volumio[1065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 17:48:43 volumio sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 17:47 Aug 29 17:48:43 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"