-- Logs begin at Thu 2024-04-11 11:17:50 UTC, end at Thu 2024-04-11 11:49:00 UTC. -- Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::volumioNext Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::next Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::stop Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::stPlaybackTimer Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::serviceStop Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::serviceStop Apr 11 11:48:06 volumiow volumio[999]: info: ControllerMpd::stop Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:06 volumiow volumio[999]: info: Apr 11 11:48:06 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand stop took 11 milliseconds Apr 11 11:48:06 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::play index undefined Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::clearAddPlayTracks NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand status took 2 milliseconds Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand stop took 2 milliseconds Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 11:48:06 volumiow volumio[999]: info: Apr 11 11:48:06 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:06 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand clear took 2 milliseconds Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma" Apr 11 11:48:06 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: 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":"This Path Tonight","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/01 This Path Tonight.wma","trackType":"wma"} Apr 11 11:48:06 volumiow volumio[999]: verbose: CURRENT POSITION 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::syncState stateService stop Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:06 volumiow volumio[999]: info: No code Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:06 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:06 volumiow volumio[999]: info: ------------------------------ 21ms Apr 11 11:48:06 volumiow volumio[999]: error: updateQueue error: null Apr 11 11:48:06 volumiow volumio[999]: info: Apr 11 11:48:06 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:06 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:06 volumiow volumio[999]: info: ------------------------------ 21ms Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma" took 20 milliseconds Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand play Apr 11 11:48:06 volumiow volumio[999]: info: ------------------------------ 17ms Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand play took 18 milliseconds Apr 11 11:48:06 volumiow volumio[999]: info: Apr 11 11:48:06 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:06 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand status took 10 milliseconds Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:06 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 11:48:06 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:06 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:06 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:06 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:06 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Myself at Last","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma","trackType":"wma"} Apr 11 11:48:06 volumiow volumio[999]: verbose: CURRENT POSITION 1 Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:06 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:06 volumiow volumio[999]: info: ------------------------------ 12ms Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::volumioSeek Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::seek Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: info: TRACKBLOCK {"uri":"music-library/NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma","service":"mpd","name":"2 - Myself at Last","artist":"Graham Nash","album":"This Path Tonight","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=507&web=Graham%20Nash/This%20Path%20Tonight/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGraham%20Nash%2FThis%20Path%20Tonight&metadata=false","duration":319,"trackType":"wma"} Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: info: ControllerMpd::seek Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:08 volumiow volumio[999]: error: null Apr 11 11:48:08 volumiow volumio[999]: info: Apr 11 11:48:08 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:08 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:08 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:08 volumiow volumio[999]: info: sendMpdCommand status took 11 milliseconds Apr 11 11:48:08 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:08 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:08 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 11:48:08 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:08 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119000,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1152 Kbps","isStreaming":false,"title":"Myself at Last","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma","trackType":"wma"} Apr 11 11:48:08 volumiow volumio[999]: verbose: CURRENT POSITION 1 Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:08 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:08 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:08 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:08 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:08 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:08 volumiow volumio[999]: info: ------------------------------ 39ms Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioNext Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::next Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::stop Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::stPlaybackTimer Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::serviceStop Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 1 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::serviceStop Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::stop Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:10 volumiow volumio[999]: info: Apr 11 11:48:10 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand stop took 14 milliseconds Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::play index undefined Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::clearAddPlayTracks NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand status took 1 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand stop took 4 milliseconds Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: 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":"Myself at Last","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/02 Myself at Last.wma","trackType":"wma"} Apr 11 11:48:10 volumiow volumio[999]: verbose: CURRENT POSITION 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState stateService stop Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: No code Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: ------------------------------ 24ms Apr 11 11:48:10 volumiow volumio[999]: info: Apr 11 11:48:10 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:10 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand clear took 19 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma" Apr 11 11:48:10 volumiow volumio[999]: error: updateQueue error: null Apr 11 11:48:10 volumiow volumio[999]: info: Apr 11 11:48:10 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:10 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:10 volumiow volumio[999]: info: ------------------------------ 2ms Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma" took 1 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand play Apr 11 11:48:10 volumiow volumio[999]: info: ------------------------------ 5ms Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand play took 4 milliseconds Apr 11 11:48:10 volumiow volumio[999]: info: Apr 11 11:48:10 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:10 volumiow volumio[999]: info: Apr 11 11:48:10 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand status took 12 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand status took 6 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 11:48:10 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:10 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cracks in the City","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma","trackType":"wma"} Apr 11 11:48:10 volumiow volumio[999]: verbose: CURRENT POSITION 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:10 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cracks in the City","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma","trackType":"wma"} Apr 11 11:48:10 volumiow volumio[999]: verbose: CURRENT POSITION 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:10 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:10 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:10 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:10 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:10 volumiow volumio[999]: info: ------------------------------ 35ms Apr 11 11:48:10 volumiow volumio[999]: info: ------------------------------ 28ms Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::volumioSeek Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::seek Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: info: TRACKBLOCK {"uri":"music-library/NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma","service":"mpd","name":"3 - Cracks in the City","artist":"Graham Nash","album":"This Path Tonight","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=507&web=Graham%20Nash/This%20Path%20Tonight/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGraham%20Nash%2FThis%20Path%20Tonight&metadata=false","duration":222,"trackType":"wma","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: info: ControllerMpd::seek Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:12 volumiow volumio[999]: error: null Apr 11 11:48:12 volumiow volumio[999]: info: Apr 11 11:48:12 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:12 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:12 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:12 volumiow volumio[999]: info: sendMpdCommand status took 2 milliseconds Apr 11 11:48:12 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:12 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:12 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 11:48:12 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:12 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90000,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1152 Kbps","isStreaming":false,"title":"Cracks in the City","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma","trackType":"wma"} Apr 11 11:48:12 volumiow volumio[999]: verbose: CURRENT POSITION 2 Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:12 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:12 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:12 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:12 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:12 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:12 volumiow volumio[999]: info: ------------------------------ 27ms Apr 11 11:48:21 volumiow volumio[999]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Apr 11 11:48:21 volumiow volumio[999]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:106:19) Apr 11 11:48:21 volumiow volumio[999]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:37) Apr 11 11:48:21 volumiow volumio[999]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Apr 11 11:48:21 volumiow volumio[999]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 11 11:48:21 volumiow volumio[999]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:144:13) Apr 11 11:48:21 volumiow volumio[999]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:114:3) Apr 11 11:48:21 volumiow volumio[999]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 11 11:48:21 volumiow volumio[999]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Apr 11 11:48:21 volumiow volumio[999]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Apr 11 11:48:21 volumiow volumio[999]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Apr 11 11:48:21 volumiow volumio[999]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Apr 11 11:48:21 volumiow volumio[999]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Apr 11 11:48:21 volumiow volumio[999]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Apr 11 11:48:21 volumiow volumio[999]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Apr 11 11:48:21 volumiow volumio[999]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 11 11:48:32 volumiow nmbd[681]: [2024/04/11 11:48:32.837076, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 11 11:48:32 volumiow nmbd[681]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.105 for name WORKGROUP<1d>. Apr 11 11:48:32 volumiow nmbd[681]: This response was from IP 192.168.5.107, reporting an IP address of 192.168.5.107. Apr 11 11:48:32 volumiow kernel: perf: interrupt took too long (3932 > 3931), lowering kernel.perf_event_max_sample_rate to 50750 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioNext Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::next Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::stop Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::stPlaybackTimer Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::serviceStop Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 2 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::serviceStop Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::stop Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:36 volumiow volumio[999]: info: Apr 11 11:48:36 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand stop took 12 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::play index undefined Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::clearAddPlayTracks NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand status took 4 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand stop took 3 milliseconds Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 11:48:36 volumiow volumio[999]: info: Apr 11 11:48:36 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:36 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand clear took 1 milliseconds Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma" Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: 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":"Cracks in the City","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/03 Cracks in the City.wma","trackType":"wma"} Apr 11 11:48:36 volumiow volumio[999]: verbose: CURRENT POSITION 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState stateService stop Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: No code Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: ------------------------------ 29ms Apr 11 11:48:36 volumiow volumio[999]: error: updateQueue error: null Apr 11 11:48:36 volumiow volumio[999]: info: Apr 11 11:48:36 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:36 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:36 volumiow volumio[999]: info: ------------------------------ 26ms Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand add "NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma" took 26 milliseconds Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand play Apr 11 11:48:36 volumiow volumio[999]: info: ------------------------------ 12ms Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand play took 12 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: Apr 11 11:48:36 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:36 volumiow volumio[999]: info: Apr 11 11:48:36 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand status took 1 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand status took 18 milliseconds Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beneath the Waves","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma","trackType":"wma"} Apr 11 11:48:36 volumiow volumio[999]: verbose: CURRENT POSITION 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:36 volumiow volumio[999]: info: ------------------------------ 25ms Apr 11 11:48:36 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 11 11:48:36 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:36 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beneath the Waves","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma","trackType":"wma"} Apr 11 11:48:36 volumiow volumio[999]: verbose: CURRENT POSITION 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:36 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:36 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:36 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:36 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:36 volumiow volumio[999]: info: ------------------------------ 73ms Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::volumioSeek Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::seek Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: info: TRACKBLOCK {"uri":"music-library/NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma","service":"mpd","name":"4 - Beneath the Waves","artist":"Graham Nash","album":"This Path Tonight","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=507&web=Graham%20Nash/This%20Path%20Tonight/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGraham%20Nash%2FThis%20Path%20Tonight&metadata=false","duration":243,"trackType":"wma","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: info: ControllerMpd::seek Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:38 volumiow volumio[999]: error: null Apr 11 11:48:38 volumiow volumio[999]: info: Apr 11 11:48:38 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:38 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:38 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:38 volumiow volumio[999]: info: sendMpdCommand status took 1 milliseconds Apr 11 11:48:38 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:38 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:38 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 11:48:38 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:38 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1152 Kbps","isStreaming":false,"title":"Beneath the Waves","artist":"Graham Nash","album":"This Path Tonight","uri":"NAS/Akselino2/Music from Bo/Graham Nash/This Path Tonight/04 Beneath the Waves.wma","trackType":"wma"} Apr 11 11:48:38 volumiow volumio[999]: verbose: CURRENT POSITION 3 Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:38 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:38 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:38 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:38 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:38 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:38 volumiow volumio[999]: info: ------------------------------ 16ms Apr 11 11:48:42 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:42 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:42 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:42 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:49 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 11 11:48:49 volumiow volumio[999]: info: CURURI: genres://Folk/Gretchen%20Peters/Blackbirds Apr 11 11:48:49 volumiow volumio[999]: info: Preload queue cleared Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/02 Pretty Things.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/03 When All You Got Is a Hammer.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/04 Everything Falls Away.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/05 The House on Auburn Street.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/06 When You Comin' Home.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/07 Jubilee.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/08 Black Ribbons.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/09 Nashville.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/10 The Cure for the Pain.wma Apr 11 11:48:49 volumiow volumio[999]: info: Preloading song: music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/11 Blackbirds (Reprise).wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F01%20Blackbirds.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/02 Pretty Things.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F02%20Pretty%20Things.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/02 Pretty Things.wma Apr 11 11:48:49 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/03 When All You Got Is a Hammer.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F03%20When%20All%20You%20Got%20Is%20a%20Hammer.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/03 When All You Got Is a Hammer.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/04 Everything Falls Away.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F04%20Everything%20Falls%20Away.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/04 Everything Falls Away.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/05 The House on Auburn Street.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F05%20The%20House%20on%20Auburn%20Street.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/05 The House on Auburn Street.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/06 When You Comin' Home.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters%3B%20Jimmy%20LaFave/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F06%20When%20You%20Comin'%20Home.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/06 When You Comin' Home.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/07 Jubilee.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F07%20Jubilee.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/07 Jubilee.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/08 Black Ribbons.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F08%20Black%20Ribbons.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/08 Black Ribbons.wma Apr 11 11:48:49 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/09 Nashville.wma in service mpd Apr 11 11:48:49 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F09%20Nashville.wma&metadata=false Apr 11 11:48:49 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/09 Nashville.wma Apr 11 11:48:50 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/10 The Cure for the Pain.wma in service mpd Apr 11 11:48:50 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F10%20The%20Cure%20for%20the%20Pain.wma&metadata=false Apr 11 11:48:50 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/10 The Cure for the Pain.wma Apr 11 11:48:50 volumiow volumio[999]: info: Exploding uri music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/11 Blackbirds (Reprise).wma in service mpd Apr 11 11:48:50 volumiow volumio[999]: info: ALBUMART /albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds%2F11%20Blackbirds%20(Reprise).wma&metadata=false Apr 11 11:48:50 volumiow volumio[999]: info: URI /mnt/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/11 Blackbirds (Reprise).wma Apr 11 11:48:51 volumiow volumio[999]: info: Preload queue cleared Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::ClearQueue Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::stop Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::stPlaybackTimer Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::serviceStop Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 3 Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::serviceStop Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::stop Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::clearPlayQueue Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::saveQueue Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPushQueue Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::addQueueItems Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::addQueueItems Apr 11 11:48:51 volumiow volumio[999]: info: Preload queue cleared Apr 11 11:48:51 volumiow volumio[999]: info: Adding Item to queue: genres://Folk/Gretchen%20Peters/Blackbirds Apr 11 11:48:51 volumiow volumio[999]: info: Exploding uri genres://Folk/Gretchen%20Peters/Blackbirds in service mpd Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPushQueue Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::saveQueue Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::updateTrackBlock Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrackBlock Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPlay Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::play index 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::stop Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::play index undefined Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::clearAddPlayTracks NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:48:51 volumiow volumio[999]: info: Apr 11 11:48:51 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand stop took 30 milliseconds Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand stop took 2 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 11:48:51 volumiow volumio[999]: info: Apr 11 11:48:51 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:51 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand status took 2 milliseconds Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand clear took 3 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand add "NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma" Apr 11 11:48:51 volumiow volumio[999]: error: updateQueue error: null Apr 11 11:48:51 volumiow volumio[999]: info: Apr 11 11:48:51 volumiow volumio[999]: ---------------------------- MPD announces system playlist update Apr 11 11:48:51 volumiow volumio[999]: info: Ignoring MPD Status Update Apr 11 11:48:51 volumiow volumio[999]: info: ------------------------------ 6ms Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand add "NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma" took 4 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand play Apr 11 11:48:51 volumiow volumio[999]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 11 11:48:51 volumiow volumio[999]: info: ------------------------------ 10ms Apr 11 11:48:51 volumiow volumio[999]: info: ------------------------------ 5ms Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand play took 3 milliseconds Apr 11 11:48:51 volumiow volumio[999]: info: Apr 11 11:48:51 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:51 volumiow volumio[999]: info: Apr 11 11:48:51 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand status took 3 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand status took 3 milliseconds Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blackbirds","artist":"Gretchen Peters","album":"Blackbirds","uri":"NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma","trackType":"wma"} Apr 11 11:48:51 volumiow volumio[999]: verbose: CURRENT POSITION 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus stop Apr 11 11:48:51 volumiow volumio[999]: info: ------------------------------ 11ms Apr 11 11:48:51 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 11:48:51 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:51 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blackbirds","artist":"Gretchen Peters","album":"Blackbirds","uri":"NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma","trackType":"wma"} Apr 11 11:48:51 volumiow volumio[999]: verbose: CURRENT POSITION 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:51 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:51 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:51 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:51 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:51 volumiow volumio[999]: info: ------------------------------ 20ms Apr 11 11:48:51 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:51 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:52 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:52 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:53 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:53 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:53 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:53 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:55 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:55 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:55 volumiow volumio[999]: info: Executing endpoint metavolumio Apr 11 11:48:55 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 11 11:48:56 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:56 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:56 volumiow volumio[999]: info: Executing endpoint getSimilarAlbums Apr 11 11:48:56 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::volumioSeek Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::seek Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: info: TRACKBLOCK {"uri":"music-library/NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma","service":"mpd","name":"1 - Blackbirds","artist":"Gretchen Peters","album":"Blackbirds","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=507&web=Gretchen%20Peters/Blackbirds/mega&path=%2Fmnt%2FNAS%2FAkselino2%2FMusic%20from%20Bo%2FGretchen%20Peters%2FBlackbirds&metadata=false","duration":273,"trackType":"wma","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::startPlaybackTimer Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: info: ControllerMpd::seek Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:59 volumiow volumio[999]: error: null Apr 11 11:48:59 volumiow volumio[999]: info: Apr 11 11:48:59 volumiow volumio[999]: ---------------------------- MPD announces state update: player Apr 11 11:48:59 volumiow volumio[999]: info: ControllerMpd::getState Apr 11 11:48:59 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:48:59 volumiow volumio[999]: info: sendMpdCommand status took 1 milliseconds Apr 11 11:48:59 volumiow volumio[999]: verbose: ControllerMpd::parseState Apr 11 11:48:59 volumiow volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 11:48:59 volumiow volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 11:48:59 volumiow volumio[999]: verbose: ControllerMpd::parseTrackInfo Apr 11 11:48:59 volumiow volumio[999]: info: ControllerMpd::pushState Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::servicePushState Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80000,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1152 Kbps","isStreaming":false,"title":"Blackbirds","artist":"Gretchen Peters","album":"Blackbirds","uri":"NAS/Akselino2/Music from Bo/Gretchen Peters/Blackbirds/01 Blackbirds.wma","trackType":"wma"} Apr 11 11:48:59 volumiow volumio[999]: verbose: CURRENT POSITION 0 Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::syncState stateService play Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::syncState currentStatus play Apr 11 11:48:59 volumiow volumio[999]: info: Received an update from plugin. extracting info from payload Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:59 volumiow volumio[999]: info: CoreStateMachine::pushState Apr 11 11:48:59 volumiow volumio[999]: info: CorePlayQueue::getTrack 0 Apr 11 11:48:59 volumiow volumio[999]: info: CoreCommandRouter::volumioPushState Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output update for this device Apr 11 11:48:59 volumiow volumio[999]: info: MRS: Pushing multiroomSync output Apr 11 11:48:59 volumiow volumio[999]: info: ------------------------------ 48ms Apr 11 11:48:59 volumiow volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 11:48:59 volumiow volumio[999]: Error: connect ETIMEDOUT 146.75.74.79:443 Apr 11 11:48:59 volumiow volumio[999]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 11 11:48:59 volumiow volumio[999]: errno: -110, Apr 11 11:48:59 volumiow volumio[999]: code: 'ETIMEDOUT', Apr 11 11:48:59 volumiow volumio[999]: syscall: 'connect', Apr 11 11:48:59 volumiow volumio[999]: address: '146.75.74.79', Apr 11 11:48:59 volumiow volumio[999]: port: 443 Apr 11 11:48:59 volumiow volumio[999]: } Apr 11 11:48:59 volumiow volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 11:49:00 volumiow sudo[3650]: volumio : unable to resolve host volumiow Apr 11 11:49:00 volumiow sudo[3650]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 11 11:49:00 volumiow sudo[3650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 11:48 Apr 11 11:49:00 volumiow sudo[3650]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"