-- Logs begin at Wed 2024-05-08 18:34:33 UTC, end at Wed 2024-05-08 18:46:00 UTC. -- May 08 18:45:15 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 18:45:15 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:15 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 18:45:15 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 18:45:16 volumio volumio[982]: info: Discovery: Getting this device information May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 18:45:16 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConf May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConf May 08 18:45:16 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 18:45:16 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:45:16 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root May 08 18:45:16 volumio sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 18:45:16 volumio sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:45:16 volumio sudo[2785]: pam_unix(sudo:session): session closed for user root May 08 18:45:16 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 18:45:16 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::volumioGetVisibleSources May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 18:45:17 volumio volumio[982]: info: Listing playlists May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 18:45:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 18:45:19 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 18:45:35 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 18:45:35 volumio volumio[982]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 08 18:45:35 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 08 18:45:35 volumio volumio[982]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio May 08 18:45:35 volumio volumio[982]: info: Received Get System Version May 08 18:45:35 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 18:45:53 volumio volumio[982]: info: CoreCommandRouter::volumioRandom May 08 18:45:53 volumio volumio[982]: info: CoreCommandRouter::writePlayerControls May 08 18:45:53 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:53 volumio volumio[982]: info: CoreStateMachine::setRandom true May 08 18:45:53 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:53 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:53 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioNext May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::next May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::stop May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:54 volumio volumio[982]: verbose: UNSET VOLATILE May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::stop May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:54 volumio volumio[982]: info: May 08 18:45:54 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand stop took 104 milliseconds May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand status took 11 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand stop took 10 milliseconds May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: 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":"La notte se n'è andata","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a","trackType":"m4a"} May 08 18:45:54 volumio volumio[982]: verbose: CURRENT POSITION 12 May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: No code May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: ------------------------------ 44ms May 08 18:45:54 volumio volumio[982]: info: May 08 18:45:54 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:54 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand clear took 34 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a" May 08 18:45:54 volumio volumio[982]: error: updateQueue error: null May 08 18:45:54 volumio volumio[982]: info: May 08 18:45:54 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:54 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:54 volumio volumio[982]: info: ------------------------------ 14ms May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a" took 9 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 18:45:54 volumio volumio[982]: info: ------------------------------ 7ms May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand play took 3 milliseconds May 08 18:45:54 volumio volumio[982]: info: May 08 18:45:54 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:54 volumio volumio[982]: info: May 08 18:45:54 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"Tanco del murazzo","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a","trackType":"m4a"} May 08 18:45:54 volumio volumio[982]: verbose: CURRENT POSITION 12 May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: ------------------------------ 42ms May 08 18:45:54 volumio volumio[982]: info: sendMpdCommand playlistinfo took 33 milliseconds May 08 18:45:54 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:54 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"Tanco del murazzo","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a","trackType":"m4a"} May 08 18:45:54 volumio volumio[982]: verbose: CURRENT POSITION 12 May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 18:45:54 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:54 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:54 volumio volumio[982]: info: ------------------------------ 71ms May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioNext May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::next May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::stop May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:56 volumio volumio[982]: verbose: UNSET VOLATILE May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 12 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::stop May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:56 volumio volumio[982]: info: May 08 18:45:56 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand stop took 93 milliseconds May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand status took 5 milliseconds May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand stop took 3 milliseconds May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 18:45:56 volumio volumio[982]: info: May 08 18:45:56 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:56 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand clear took 3 milliseconds May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a" May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: 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":"Tanco del murazzo","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/13 Tanco del murazzo.m4a","trackType":"m4a"} May 08 18:45:56 volumio volumio[982]: verbose: CURRENT POSITION 9 May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: No code May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: ------------------------------ 24ms May 08 18:45:56 volumio volumio[982]: error: updateQueue error: null May 08 18:45:56 volumio volumio[982]: info: May 08 18:45:56 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:56 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:56 volumio volumio[982]: info: ------------------------------ 24ms May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a" took 22 milliseconds May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 18:45:56 volumio volumio[982]: info: ------------------------------ 7ms May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand play took 6 milliseconds May 08 18:45:56 volumio volumio[982]: info: May 08 18:45:56 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:56 volumio volumio[982]: info: May 08 18:45:56 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"932 Kbps","isStreaming":false,"title":"Contrada Chiavicone","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a","trackType":"m4a"} May 08 18:45:56 volumio volumio[982]: verbose: CURRENT POSITION 9 May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: ------------------------------ 25ms May 08 18:45:56 volumio volumio[982]: info: sendMpdCommand playlistinfo took 15 milliseconds May 08 18:45:56 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:56 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":152,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"932 Kbps","isStreaming":false,"title":"Contrada Chiavicone","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a","trackType":"m4a"} May 08 18:45:56 volumio volumio[982]: verbose: CURRENT POSITION 9 May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 18:45:56 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:56 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:56 volumio volumio[982]: info: ------------------------------ 50ms May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::seek May 08 18:45:58 volumio volumio[982]: error: null May 08 18:45:58 volumio volumio[982]: info: May 08 18:45:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:58 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"932 Kbps","isStreaming":false,"title":"Contrada Chiavicone","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a","trackType":"m4a"} May 08 18:45:58 volumio volumio[982]: verbose: CURRENT POSITION 9 May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 18:45:58 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: ------------------------------ 32ms May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::stop May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:58 volumio volumio[982]: verbose: UNSET VOLATILE May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::stop May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:58 volumio volumio[982]: info: May 08 18:45:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:58 volumio volumio[982]: info: sendMpdCommand stop took 57 milliseconds May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:58 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 18:45:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: 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":"Contrada Chiavicone","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/10 Contrada Chiavicone.m4a","trackType":"m4a"} May 08 18:45:58 volumio volumio[982]: verbose: CURRENT POSITION 9 May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: No code May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrack 9 May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:58 volumio volumio[982]: info: ------------------------------ 46ms May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:58 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:58 volumio volumio[982]: info: CoreStateMachine::serviceClearAddPlay May 08 18:45:58 volumio volumio[982]: info: CoreCommandRouter::serviceClearAddPlayTracks May 08 18:45:58 volumio volumio[982]: error: WARNING: No clearAddPlayTracks method for service mpd May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::serviceClearAddPlay May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::serviceClearAddPlayTracks May 08 18:45:59 volumio volumio[982]: error: WARNING: No clearAddPlayTracks method for service mpd May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand stop took 3 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:59 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand clear took 1 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a" May 08 18:45:59 volumio volumio[982]: error: updateQueue error: null May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:59 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 2ms May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a" took 2 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 12ms May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand play took 12 milliseconds May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand status took 2 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand status took 1 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"789 Kbps","isStreaming":false,"title":"La notte se n'è andata","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a","trackType":"m4a"} May 08 18:45:59 volumio volumio[982]: verbose: CURRENT POSITION 2 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 19ms May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand playlistinfo took 13 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"789 Kbps","isStreaming":false,"title":"La notte se n'è andata","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a","trackType":"m4a"} May 08 18:45:59 volumio volumio[982]: verbose: CURRENT POSITION 2 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 18:45:59 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 39ms May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::stop May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:59 volumio volumio[982]: verbose: UNSET VOLATILE May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::stop May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand stop took 50 milliseconds May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand status took 3 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: 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":"La notte se n'è andata","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/03 La notte se n'è andata.m4a","trackType":"m4a"} May 08 18:45:59 volumio volumio[982]: verbose: CURRENT POSITION 2 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: No code May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 2 May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 27ms May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 0 May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrack 0 May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA_/Capossela V_/Il ballo di S. Vito/01 Il ballo di S. Vito.m4a May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand stop took 3 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:59 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand clear took 1 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/01 Il ballo di S. Vito.m4a" May 08 18:45:59 volumio volumio[982]: error: updateQueue error: null May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 18:45:59 volumio volumio[982]: info: Ignoring MPD Status Update May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 2ms May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand add "USB/MUSICA_/Capossela V_/Il ballo di S. Vito/01 Il ballo di S. Vito.m4a" took 1 milliseconds May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 18:45:59 volumio volumio[982]: info: ------------------------------ 6ms May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand play took 5 milliseconds May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:59 volumio volumio[982]: info: May 08 18:45:59 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 18:45:59 volumio volumio[982]: info: sendMpdCommand status took 5 milliseconds May 08 18:45:59 volumio volumio[982]: info: ControllerMpd::getState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:45:59 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:45:59 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:45:59 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 18:45:59 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::serviceClearAddPlay May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::serviceClearAddPlayTracks May 08 18:46:00 volumio volumio[982]: error: WARNING: No clearAddPlayTracks method for service mpd May 08 18:46:00 volumio volumio[982]: info: sendMpdCommand status took 8 milliseconds May 08 18:46:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 18:46:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:46:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 9 milliseconds May 08 18:46:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:46:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Il ballo di S. Vito","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/01 Il ballo di S. Vito.m4a","trackType":"m4a"} May 08 18:46:00 volumio volumio[982]: verbose: CURRENT POSITION 13 May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:46:00 volumio volumio[982]: info: ------------------------------ 27ms May 08 18:46:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 11 milliseconds May 08 18:46:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 18:46:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Il ballo di S. Vito","artist":"Capossela V.","album":"Il ballo di S. Vito","uri":"USB/MUSICA_/Capossela V_/Il ballo di S. Vito/01 Il ballo di S. Vito.m4a","trackType":"m4a"} May 08 18:46:00 volumio volumio[982]: verbose: CURRENT POSITION 13 May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 18:46:00 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:46:00 volumio volumio[982]: info: ------------------------------ 40ms May 08 18:46:00 volumio volumio[982]: info: CoreCommandRouter::volumioPrevious May 08 18:46:00 volumio volumio[982]: info: CoreStateMachine::previous May 08 18:46:00 volumio volumio[982]: info: CorePlayQueue::getTrack 13 May 08 18:46:00 volumio volumio[982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:46:00 volumio volumio[982]: TypeError: Cannot read property 'service' of undefined May 08 18:46:00 volumio volumio[982]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1293:97) May 08 18:46:00 volumio volumio[982]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:87:28) May 08 18:46:00 volumio volumio[982]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:233:33) May 08 18:46:00 volumio volumio[982]: at emitTwo (events.js:126:13) May 08 18:46:00 volumio volumio[982]: at Socket.emit (events.js:214:7) May 08 18:46:00 volumio volumio[982]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 08 18:46:00 volumio volumio[982]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 08 18:46:00 volumio volumio[982]: at process._tickCallback (internal/process/next_tick.js:180:9) May 08 18:46:00 volumio volumio[982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:46:00 volumio sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 18:45 May 08 18:46:00 volumio sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"