-- Logs begin at Wed 2025-09-17 12:17:01 +04, end at Wed 2025-09-17 13:24:31 +04. -- Sep 17 13:23:46 lobby sshd[12483]: Received disconnect from 192.168.2.1 port 65221:11: disconnected by user Sep 17 13:23:46 lobby sshd[12483]: Disconnected from user volumio 192.168.2.1 port 65221 Sep 17 13:23:46 lobby sshd[12474]: pam_unix(sshd:session): session closed for user volumio Sep 17 13:23:46 lobby systemd[1]: session-34.scope: Succeeded. Sep 17 13:23:47 lobby systemd-logind[587]: Session 34 logged out. Waiting for processes to exit. Sep 17 13:23:47 lobby systemd-logind[587]: Removed session 34. Sep 17 13:23:47 lobby sshd[12534]: Accepted publickey for volumio from 192.168.2.1 port 65227 ssh2: RSA SHA256:+ZqEzlJXWcAGSfDzmm+baP9rZyx3J1wnRVkNditwAjI Sep 17 13:23:47 lobby sshd[12534]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 17 13:23:47 lobby systemd-logind[587]: New session 35 of user volumio. Sep 17 13:23:47 lobby systemd[1]: Started Session 35 of user volumio. Sep 17 13:23:47 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:47 lobby volumio[12172]: info: Sep 17 13:23:47 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:47 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:47 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:47 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:47 lobby volumio[12172]: info: Sep 17 13:23:47 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:47 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:47 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:47 lobby volumio[12172]: info: sendMpdCommand status took 16 milliseconds Sep 17 13:23:47 lobby volumio[12172]: info: sendMpdCommand status took 11 milliseconds Sep 17 13:23:47 lobby volumio[12172]: info: sendMpdCommand status took 10 milliseconds Sep 17 13:23:47 lobby volumio[12172]: info: sendMpdCommand status took 6 milliseconds Sep 17 13:23:47 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:49 lobby volumio[12172]: info: Command Router : Notfying DB Updatetrue Sep 17 13:23:49 lobby sshd[12543]: Received disconnect from 192.168.2.1 port 65227:11: disconnected by user Sep 17 13:23:49 lobby sshd[12543]: Disconnected from user volumio 192.168.2.1 port 65227 Sep 17 13:23:49 lobby sshd[12534]: pam_unix(sshd:session): session closed for user volumio Sep 17 13:23:49 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:49 lobby systemd[1]: session-35.scope: Succeeded. Sep 17 13:23:49 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:49 lobby systemd-logind[587]: Session 35 logged out. Waiting for processes to exit. Sep 17 13:23:49 lobby systemd-logind[587]: Removed session 35. Sep 17 13:23:50 lobby volumio[12172]: info: Command Router : Notfying DB Updatetrue Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:23:50 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:50 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:50 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: No code Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:50 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: No code Sep 17 13:23:50 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:50 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:50 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:50 lobby volumio[12172]: info: ------------------------------ 3113ms Sep 17 13:23:50 lobby volumio[12172]: info: ------------------------------ 3107ms Sep 17 13:23:50 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:50 lobby volumio[12172]: info: Sep 17 13:23:50 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:50 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:50 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:50 lobby volumio[12172]: info: sendMpdCommand status took 6 milliseconds Sep 17 13:23:50 lobby volumio[12172]: info: sendMpdCommand status took 4 milliseconds Sep 17 13:23:50 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:51 lobby volumio[12172]: info: Command Router : Notfying DB Updatetrue Sep 17 13:23:51 lobby volumio[12172]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:51 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:51 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:51 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:51 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:51 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:51 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:51 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:51 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:51 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:51 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:51 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:51 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:51 lobby volumio[12172]: info: No code Sep 17 13:23:51 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:51 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:51 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:51 lobby volumio[12172]: info: ------------------------------ 410ms Sep 17 13:23:51 lobby volumio[12172]: info: MPD Database updated - AlbumList cache refreshed Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: database Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: MPD Database updated - AlbumList cache refreshed Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: database Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: MPD Database updated - AlbumList cache refreshed Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: database Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: Sep 17 13:23:51 lobby volumio[12172]: ---------------------------- MPD announces state update: update Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: ControllerMpd::getState Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 159 milliseconds Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 157 milliseconds Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 157 milliseconds Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 154 milliseconds Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 152 milliseconds Sep 17 13:23:51 lobby volumio[12172]: info: sendMpdCommand status took 151 milliseconds Sep 17 13:23:51 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: info: Command Router : Notfying DB Updatefalse Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: info: Command Router : Notfying DB Updatefalse Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 893ms Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 895ms Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 890ms Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 891ms Sep 17 13:23:52 lobby volumio[12172]: info: sendMpdCommand status took 924 milliseconds Sep 17 13:23:52 lobby volumio[12172]: info: sendMpdCommand status took 922 milliseconds Sep 17 13:23:52 lobby volumio[12172]: info: sendMpdCommand status took 922 milliseconds Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: info: Command Router : Notfying DB Updatefalse Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: verbose: ControllerMpd::parseState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ControllerMpd::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::servicePushState Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:23:52 lobby volumio[12172]: verbose: CURRENT POSITION 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState stateService stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: No code Sep 17 13:23:52 lobby volumio[12172]: info: CoreStateMachine::pushState Sep 17 13:23:52 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:23:52 lobby volumio[12172]: info: CoreCommandRouter::volumioPushState Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 1031ms Sep 17 13:23:52 lobby volumio[12172]: info: ------------------------------ 1033ms Sep 17 13:24:01 lobby CRON[12690]: pam_unix(cron:session): session opened for user root by (uid=0) Sep 17 13:24:01 lobby CRON[12691]: (root) CMD (/root/sycfm/bin/mp-sw.sh 1) Sep 17 13:24:01 lobby volumio[12172]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:01 lobby volumio[12172]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:01 lobby sudo[12704]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/usr/bin/killall -9 mpd Sep 17 13:24:01 lobby sudo[12704]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:01 lobby sudo[12704]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:01 lobby systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Sep 17 13:24:01 lobby systemd[1]: mpd.service: Failed with result 'signal'. Sep 17 13:24:01 lobby sudo[12706]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart mpd Sep 17 13:24:01 lobby sudo[12706]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:01 lobby systemd[1]: Starting Music Player Daemon... Sep 17 13:24:02 lobby sudo[12708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 13:24:02 lobby sudo[12708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:02 lobby sudo[12708]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:03 lobby mpd[12710]: Sep 17 13:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 13:24:03 lobby systemd[1]: Started Music Player Daemon. Sep 17 13:24:03 lobby sudo[12706]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:03 lobby sudo[12713]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart volumio Sep 17 13:24:03 lobby sudo[12713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:03 lobby systemd[1]: Started dynamicswap service. Sep 17 13:24:03 lobby systemd[1]: Stopping Volumio Backend Module... Sep 17 13:24:03 lobby volumio-remote-updater[583]: [2025-09-17 13:24:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 17 13:24:03 lobby volumio-remote-updater[583]: [2025-09-17 13:24:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 17 13:24:03 lobby systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Sep 17 13:24:03 lobby systemd[1]: volumio.service: Succeeded. Sep 17 13:24:03 lobby systemd[1]: Stopped Volumio Backend Module. Sep 17 13:24:03 lobby systemd[1]: dynamicswap.service: Succeeded. Sep 17 13:24:03 lobby systemd[1]: Started Volumio Backend Module. Sep 17 13:24:03 lobby sudo[12713]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:04 lobby ntpd[986]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Sep 17 13:24:06 lobby volumio[12723]: info: ------------------------------------------- Sep 17 13:24:06 lobby volumio[12723]: info: ----- Volumio3 ---- Sep 17 13:24:06 lobby volumio[12723]: info: ------------------------------------------- Sep 17 13:24:06 lobby volumio[12723]: info: ----- System startup ---- Sep 17 13:24:06 lobby volumio[12723]: info: ------------------------------------------- Sep 17 13:24:07 lobby volumio[12723]: info: Plugin folders cleanup Sep 17 13:24:07 lobby volumio[12723]: info: Scanning into folder /volumio/app/plugins/ Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category audio_interface Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category miscellanea Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category music_service Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category plugins.json Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category system_controller Sep 17 13:24:07 lobby volumio[12723]: info: Scanning category user_interface Sep 17 13:24:07 lobby volumio[12723]: info: Scanning into folder /data/plugins/ Sep 17 13:24:07 lobby volumio[12723]: info: Plugin folders cleanup completed Sep 17 13:24:07 lobby volumio[12723]: info: ------------------------------------------- Sep 17 13:24:07 lobby volumio[12723]: info: ----- Core plugins startup ---- Sep 17 13:24:07 lobby volumio[12723]: info: ------------------------------------------- Sep 17 13:24:07 lobby volumio[12723]: info: Loading plugins from folder /volumio/app/plugins/ Sep 17 13:24:07 lobby volumio[12723]: info: Adding plugin upnp to MyMusic Plugins Sep 17 13:24:07 lobby volumio[12723]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 17 13:24:07 lobby volumio[12723]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 17 13:24:07 lobby volumio[12723]: info: Loading plugins from folder /data/plugins/ Sep 17 13:24:07 lobby volumio[12723]: info: Loading plugin "system"... Sep 17 13:24:07 lobby volumio[12723]: info: Loading plugin "appearance"... Sep 17 13:24:08 lobby CRON[12138]: (CRON) info (No MTA installed, discarding output) Sep 17 13:24:08 lobby CRON[12138]: pam_unix(cron:session): session closed for user root Sep 17 13:24:08 lobby volumio-remote-updater[583]: [2025-09-17 13:24:08] [connect] Successful connection Sep 17 13:24:08 lobby volumio[12723]: info: Loading plugin "network"... Sep 17 13:24:08 lobby volumio[12723]: info: Refreshing Cached IP Addresses Sep 17 13:24:08 lobby sudo[12755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 13:24:08 lobby sudo[12755]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:08 lobby sudo[12755]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:08 lobby volumio[12723]: info: Loading plugin "services"... Sep 17 13:24:08 lobby sudo[12757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 13:24:08 lobby sudo[12757]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:08 lobby volumio[12723]: info: Loading plugin "alsa_controller"... Sep 17 13:24:08 lobby sudo[12760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 13:24:08 lobby sudo[12760]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:08 lobby sudo[12757]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:08 lobby sudo[12760]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:09 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "wizard"... Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "networkfs"... Sep 17 13:24:09 lobby volumio[12723]: info: Starting Udev Watcher for removable devices Sep 17 13:24:09 lobby volumio[12723]: info: Ignoring mount for partition: boot Sep 17 13:24:09 lobby volumio[12723]: info: Ignoring mount for partition: volumio Sep 17 13:24:09 lobby volumio[12723]: info: Ignoring mount for partition: volumio_data Sep 17 13:24:09 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "volumio_command_line_client"... Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "upnp"... Sep 17 13:24:09 lobby volumio[12723]: info: [1758101049515] Starting Upmpd Daemon Sep 17 13:24:09 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "my_music"... Sep 17 13:24:09 lobby volumio[12723]: info: Loading plugin "mpd"... Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "upnp_browser"... Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "alarm-clock"... Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "airplay_emulation"... Sep 17 13:24:10 lobby volumio[12723]: info: Starting Shairport Sync Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "last_100"... Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "webradio"... Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "i2s_dacs"... Sep 17 13:24:10 lobby volumio[12723]: info: I2S DAC not set, start Auto-detection Sep 17 13:24:10 lobby volumio[12723]: info: Loading plugin "volumiodiscovery"... Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 13:24:10 lobby node[12723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** For more information see Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 13:24:10 lobby volumio[12723]: *** WARNING *** For more information see Sep 17 13:24:10 lobby node[12723]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 13:24:10 lobby node[12723]: *** WARNING *** For more information see Sep 17 13:24:10 lobby node[12723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 13:24:10 lobby node[12723]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 13:24:10 lobby node[12723]: *** WARNING *** For more information see Sep 17 13:24:10 lobby volumio[12723]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 17 13:24:10 lobby volumio[12723]: info: Discovery: Started advertising with name: lobby Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "outputs"... Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "albumart"... Sep 17 13:24:11 lobby volumio[12723]: info: Plugin example_plugin is not enabled Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "inputs"... Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "updater_comm"... Sep 17 13:24:11 lobby volumio[12723]: info: Plugin mpdemulation is not enabled Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "rest_api"... Sep 17 13:24:11 lobby volumio[12723]: info: Loading plugin "websocket"... Sep 17 13:24:11 lobby volumio[12723]: info: Loading i18n strings for locale en Sep 17 13:24:11 lobby volumio[12723]: Updating browse sources language Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::initPlayerControls Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: Forking 3 albumart workers Sep 17 13:24:11 lobby volumio[12723]: Express server listening on port 3000 Sep 17 13:24:11 lobby volumio[12723]: [Metrics] WebUI: 6s 241.98ms Sep 17 13:24:11 lobby volumio[12723]: info: CoreStateMachine::resetVolumioState Sep 17 13:24:11 lobby volumio[12723]: info: CoreStateMachine::getcurrentVolume Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 13:24:11 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:11 lobby volumio[12723]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 17 13:24:11 lobby volumio[12723]: info: Completed loading Core Plugins Sep 17 13:24:11 lobby volumio[12723]: info: Preparing to generate the ALSA configuration file Sep 17 13:24:12 lobby volumio[12723]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 17 13:24:12 lobby volumio[12723]: wlan0 Interface doesn't support scanning. Sep 17 13:24:12 lobby volumio[12723]: info: Cannot use regular scanning, forcing with ap-force Sep 17 13:24:12 lobby sudo[12846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 17 13:24:12 lobby sudo[12846]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:12 lobby sudo[12846]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:12 lobby volumio[12723]: command failed: No such device (-19) Sep 17 13:24:12 lobby volumio[12723]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 17 13:24:12 lobby volumio[12723]: command failed: No such device (-19) Sep 17 13:24:12 lobby volumio[12723]: verbose: New Socket.io Connection to 192.168.2.76 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Total Clients: 1 Sep 17 13:24:12 lobby volumio-remote-updater[583]: [2025-09-17 13:24:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758101048 101 Sep 17 13:24:12 lobby volumio[12723]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Sep 17 13:24:12 lobby volumio[12723]: verbose: New Socket.io Connection to 192.168.2.76 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Total Clients: 3 Sep 17 13:24:12 lobby volumio[12723]: info: VolumeController:: Volume=0 Mute =false Sep 17 13:24:12 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:12 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:12 lobby volumio[12723]: info: CoreStateMachine::updateTrackBlock Sep 17 13:24:12 lobby volumio[12723]: info: CorePlayQueue::getTrackBlock Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 13:24:12 lobby volumio[12723]: info: Reloading queue from file Sep 17 13:24:12 lobby volumio[12723]: info: Asound.conf file unchanged, so no further update is needed Sep 17 13:24:12 lobby volumio[12723]: info: Output device has changed, restarting MPD Sep 17 13:24:12 lobby volumio[12723]: info: Output device has changed, restarting Shairport Sync Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:12 lobby sudo[12859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 13:24:12 lobby sudo[12859]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:12 lobby sudo[12858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 13:24:12 lobby sudo[12858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:12 lobby sudo[12858]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:12 lobby systemd[1]: Stopping Music Player Daemon... Sep 17 13:24:12 lobby volumio[12723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 13:24:12 lobby volumio[12723]: info: ___________ START PLUGINS ___________ Sep 17 13:24:12 lobby volumio[12723]: info: ControllerMpd::onStart: Initializing MPD Sep 17 13:24:12 lobby volumio[12723]: info: Creating MPD Configuration file Sep 17 13:24:12 lobby systemd[1]: mpd.service: Succeeded. Sep 17 13:24:12 lobby systemd[1]: Stopped Music Player Daemon. Sep 17 13:24:12 lobby systemd[1]: Starting Music Player Daemon... Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 13:24:12 lobby volumio[12723]: info: [1758101052914] CoreMusicLibrary::Adding element Media Servers Sep 17 13:24:12 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 13:24:12 lobby sudo[12869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 13:24:12 lobby sudo[12869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:12 lobby sudo[12866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 13:24:12 lobby sudo[12866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:12 lobby sudo[12866]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:12 lobby systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 17 13:24:12 lobby systemd[1]: mpd.service: Succeeded. Sep 17 13:24:12 lobby systemd[1]: Stopped Music Player Daemon. Sep 17 13:24:13 lobby systemd[1]: Starting Music Player Daemon... Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 13:24:13 lobby volumio[12723]: info: [1758101053179] CoreMusicLibrary::Adding element Last_100 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 13:24:13 lobby volumio[12723]: info: [1758101053189] CoreMusicLibrary::Adding element Webradio Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 13:24:13 lobby volumio[12723]: info: Volumio Calling Home Sep 17 13:24:13 lobby sudo[12872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 13:24:13 lobby sudo[12872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:13 lobby volumio[12723]: Starting albumart workers Sep 17 13:24:13 lobby sudo[12872]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:13 lobby volumio[12723]: Starting albumart workers Sep 17 13:24:13 lobby volumio[12723]: Starting albumart workers Sep 17 13:24:13 lobby volumio[12723]: info: CoreStateMachine::setRepeat null single undefined Sep 17 13:24:13 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:13 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:13 lobby volumio[12723]: info: CoreStateMachine::setRandom null Sep 17 13:24:13 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:13 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:13 lobby volumio[12723]: info: MPD Permissions set Sep 17 13:24:13 lobby volumio[12723]: info: MPD Permissions set Sep 17 13:24:13 lobby volumio[12723]: info: VolumeController:: Volume=0 Mute =false Sep 17 13:24:13 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:13 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:13 lobby volumio[12723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:13 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:14 lobby volumio[12723]: info: Discovery: adding 37197d44-ba10-490c-bfa9-298945fce0a4 Sep 17 13:24:14 lobby volumio[12723]: info: Discovery: Found device lobby Sep 17 13:24:14 lobby volumio[12723]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:14 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:14 lobby volumio[12723]: error: Could not retrieve plugin audio_interface multiroom Sep 17 13:24:14 lobby volumio[12723]: info: Starting Shairport Sync Sep 17 13:24:14 lobby volumio[12723]: info: Starting Shairport Sync Sep 17 13:24:14 lobby volumio[12723]: info: Starting Shairport Sync Sep 17 13:24:14 lobby sudo[12899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 13:24:14 lobby sudo[12899]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:14 lobby sudo[12902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 13:24:14 lobby sudo[12902]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:14 lobby sudo[12901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 13:24:14 lobby sudo[12901]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:14 lobby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 13:24:14 lobby systemd[1]: shairport-sync.service: Succeeded. Sep 17 13:24:14 lobby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 13:24:14 lobby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 13:24:14 lobby sudo[12899]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:14 lobby sudo[12902]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:14 lobby volumio[12723]: info: Volumio called home Sep 17 13:24:14 lobby systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 13:24:14 lobby systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 17 13:24:14 lobby systemd[1]: shairport-sync.service: Succeeded. Sep 17 13:24:14 lobby systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 13:24:14 lobby systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 13:24:14 lobby sudo[12901]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:14 lobby volumio[12723]: info: Shairport-Sync Started Sep 17 13:24:14 lobby volumio[12723]: Error adding Membership: Error: addMembership EINVAL Sep 17 13:24:15 lobby volumio[12723]: info: Shairport-Sync Started Sep 17 13:24:15 lobby volumio[12723]: info: Shairport-Sync Started Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:15 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:15 lobby volumio[12723]: verbose: New Socket.io Connection to 192.168.2.76 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Total Clients: 5 Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:15 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:15 lobby ntpd[986]: 89.44.86.85 local addr 192.168.2.76 -> 10.10.0.60 Sep 17 13:24:15 lobby ntpd[986]: 89.36.162.11 local addr 192.168.2.76 -> 10.10.0.60 Sep 17 13:24:15 lobby ntpd[986]: 47.91.111.180 local addr 192.168.2.76 -> 10.10.0.60 Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 13:24:15 lobby volumio[12723]: error: Could not retrieve plugin audio_interface multiroom Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::volumioGetQueue Sep 17 13:24:15 lobby volumio[12723]: info: CoreStateMachine::getQueue Sep 17 13:24:15 lobby volumio[12723]: info: CorePlayQueue::getQueue Sep 17 13:24:15 lobby volumio[12723]: info: Listing playlists Sep 17 13:24:15 lobby mpd[12883]: Sep 17 13:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 13:24:15 lobby systemd[1]: Started Music Player Daemon. Sep 17 13:24:15 lobby sudo[12859]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:15 lobby sudo[12869]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:15 lobby volumio[12723]: info: Completed starting Core Plugins Sep 17 13:24:15 lobby volumio[12723]: info: BOOT COMPLETED Sep 17 13:24:15 lobby volumio[12723]: [Metrics] CommandRouter: 9s 648.49ms Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::volumiosetStartupVolume Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:15 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:16 lobby volumio[12723]: info: MPD running with PID12883 Sep 17 13:24:16 lobby volumio[12723]: ,establishing connection Sep 17 13:24:16 lobby volumio[12723]: error: MPD error: The expression evaluated to a falsy value: Sep 17 13:24:16 lobby volumio[12723]: assert.ok(self.idling) Sep 17 13:24:16 lobby volumio[12723]: error: The expression evaluated to a falsy value: Sep 17 13:24:16 lobby volumio[12723]: assert.ok(self.idling) Sep 17 13:24:16 lobby volumio[12723]: error: MPD error: The expression evaluated to a falsy value: Sep 17 13:24:16 lobby volumio[12723]: assert.ok(self.idling) Sep 17 13:24:16 lobby volumio[12723]: error: The expression evaluated to a falsy value: Sep 17 13:24:16 lobby volumio[12723]: assert.ok(self.idling) Sep 17 13:24:16 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:16 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 17 13:24:16 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 13:24:17 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 17 13:24:19 lobby sudo[12943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 13:24:19 lobby sudo[12943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:19 lobby sudo[12943]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:19 lobby sudo[12945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 13:24:19 lobby sudo[12945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:19 lobby sudo[12949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 17 13:24:19 lobby sudo[12949]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 13:24:19 lobby sudo[12945]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:19 lobby sudo[12949]: pam_unix(sudo:session): session closed for user root Sep 17 13:24:19 lobby volumio[12723]: info: Upmpdcli Daemon Started Sep 17 13:24:21 lobby volumio[12723]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 17 13:24:23 lobby volumio[12723]: info: CoreCommandRouter::volumioGetQueue Sep 17 13:24:23 lobby volumio[12723]: info: CoreStateMachine::getQueue Sep 17 13:24:23 lobby volumio[12723]: info: CorePlayQueue::getQueue Sep 17 13:24:23 lobby volumio[12723]: info: CoreCommandRouter::volumioGetQueue Sep 17 13:24:23 lobby volumio[12723]: info: CoreStateMachine::getQueue Sep 17 13:24:23 lobby volumio[12723]: info: CorePlayQueue::getQueue Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: info: Sep 17 13:24:23 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:23 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: info: Sep 17 13:24:23 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:23 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: info: Sep 17 13:24:23 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:23 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:23 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 36 milliseconds Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 30 milliseconds Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 30 milliseconds Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 27 milliseconds Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 24 milliseconds Sep 17 13:24:23 lobby volumio[12723]: info: sendMpdCommand status took 20 milliseconds Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatetrue Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatetrue Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatetrue Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 387ms Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 382ms Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 376ms Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 11 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 8 milliseconds Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatefalse Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 113ms Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: update Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 185 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 183 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 79 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 75 milliseconds Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatefalse Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: Command Router : Notfying DB Updatefalse Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::Close All Modals sent Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 387ms Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 280ms Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioGetState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 8 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 6 milliseconds Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 92ms Sep 17 13:24:24 lobby volumio[12723]: info: ------------------------------ 90ms Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: Sep 17 13:24:24 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 90 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 8 milliseconds Sep 17 13:24:24 lobby volumio[12723]: info: sendMpdCommand status took 6 milliseconds Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:24 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: No code Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:24 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:24 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:24 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:24 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 188ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 107ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 106ms Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 161 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 45 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 41 milliseconds Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 256ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 152ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 137ms Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- Client requests Volumio stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioStop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- Client requests Volumio Clear Queue Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioClearQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::ClearQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::clearPlayQueue Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::saveQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushQueue Sep 17 13:24:25 lobby volumio[12723]: error: API:pushError: TypeError: callback.apply is not a function Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- Client requests Volumio Play Playlist 1 Sep 17 13:24:25 lobby volumio[12723]: info: Play playlist 1 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioClearQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::ClearQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::clearPlayQueue Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::saveQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioAddQueueItems Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::addQueueItems Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::addQueueItems Sep 17 13:24:25 lobby volumio[12723]: info: Preload queue cleared Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Egyptian Nile.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Egyptian Nile.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Le grand Sud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Le grand Sud.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Entspannt Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Entspannt Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Weightless - Atlantis Dove.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Weightless - Atlantis Dove.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/After Love (Ambient Mix) Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/After Love (Ambient Mix) Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Bayati Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Bayati Feras Charestan.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Nara.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Nara.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Cool Water Michael Askill,Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Cool Water Michael Askill,Omar Faruk Tekbilek.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Crying Oud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Crying Oud.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Crosswinds Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Crosswinds Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Sand of Joshua.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Sand of Joshua.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Midnight Blue Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Midnight Blue Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Ana LA Habibi Ood.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Ana LA Habibi Ood.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/5 Am Schwarz & Funk.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/5 Am Schwarz & Funk.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Ay Düşünce Mehmet F. Nalıncı.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Ay Düşünce Mehmet F. Nalıncı.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Halat Wayd In A State Of Passion Estado De Pasión.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Halat Wayd In A State Of Passion Estado De Pasión.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Blooming in the Valley - Omid Shabani.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Blooming in the Valley - Omid Shabani.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Nawa Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Nawa Feras Charestan.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Ghurbeh (Diaspora) - Ahmad Al Khatib.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Ghurbeh (Diaspora) - Ahmad Al Khatib.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/My Quiet Room Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/My Quiet Room Feras Charestan.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Blue Pacific Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Blue Pacific Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Sorry - Spa Version - Anthony Hathaway.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Sorry - Spa Version - Anthony Hathaway.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Ana Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Ana Feras Charestan.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Alief.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Alief.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Cruisin Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Cruisin Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/River of Pouyade Jacob Gurevitsch.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/River of Pouyade Jacob Gurevitsch.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Twilight Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Twilight Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Searching Serenity.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Searching Serenity.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Conte d'un luth amoureux - Abderraouf Ouertani.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Conte d'un luth amoureux - Abderraouf Ouertani.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/My Island Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/My Island Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Little Bird Joseph Tawadros.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Little Bird Joseph Tawadros.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/The Trees We Wear - Le Trio Joubran.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/The Trees We Wear - Le Trio Joubran.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Oud Suite.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Oud Suite.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/للراحة Tahta Menezes, Rabih Abou-Khalil, Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/للراحة Tahta Menezes, Rabih Abou-Khalil, Omar Faruk Tekbilek.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Takvim - Live.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Takvim - Live.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Silent Stories - Instrumentale.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Silent Stories - Instrumentale.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Winds of Change (Reprise) - Om-Official.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Winds of Change (Reprise) - Om-Official.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Walking on Sunshine Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Walking on Sunshine Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Chilled Cream Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Chilled Cream Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Fadfada Ali Sabah.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Fadfada Ali Sabah.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Puerta del Sol Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Puerta del Sol Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Hijaz - Aarif Jaman.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Hijaz - Aarif Jaman.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/The Palms Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/The Palms Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Song for Sol Jacob Gurevitsch.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Song for Sol Jacob Gurevitsch.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Desert Twilight - Brian Keane, Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Desert Twilight - Brian Keane, Omar Faruk Tekbilek.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Arabic Oud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Arabic Oud.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/El Zaitoun.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/El Zaitoun.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Smooth Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Smooth Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Intidhar - Live in Marrakech Oum.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Intidhar - Live in Marrakech Oum.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Milchbar 1000 AM Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Milchbar 1000 AM Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/City of Angels Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/City of Angels Blank & Jones.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Lost Soul Bliss.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Lost Soul Bliss.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: Adding Item to queue: mnt/INTERNAL/1 day/Color Verde.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: Exploding uri mnt/INTERNAL/1 day/Color Verde.mp3 in service mpd Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Sary%20Hany/Gaza%20Surf%20Club%20(Original%20Motion%20Picture%20Soundtrack)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FPeaceful%20Night%20in%20Gaza%20Sary%20Hany.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Yasser%20Farouk/Arabic%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FEgyptian%20Nile.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Egyptian Nile.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Fezaani/Zen/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FLe%20grand%20Sud.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Le grand Sud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Milchbar%20-%20Seaside%20Season%206/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FEntspannt%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Entspannt Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Atlantis%20Dove/Weightless/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FWeightless%20-%20Atlantis%20Dove.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Weightless - Atlantis Dove.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Milchbar%20-%20Seaside%20Season%206/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FAfter%20Love%20(Ambient%20Mix)%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/After Love (Ambient Mix) Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Feras%20Charestan/Ana/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FBayati%20Feras%20Charestan.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Bayati Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Imane%20Homsy%2CCharbel%20Rouhana/Charbel%20Rouhana-%20the%20Art%20of%20the%20Middle%20Eastern%20Oud%20-%20New%20Perspectives%20On%20Trad.%20Maqams/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FNara.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Nara.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Michael%20Askill%2COmar%20Faruk%20Tekbilek/Fata%20Morgana/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FCool%20Water%20Michael%20Askill%2COmar%20Faruk%20Tekbilek.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Cool Water Michael Askill,Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Yasser%20Farouk/Arabic%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FCrying%20Oud.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Crying Oud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Milchbar%20-%20Seaside%20Season%2012/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FCrosswinds%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Crosswinds Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Jacob%20Gurevitsch/In%20Search%20of%20Lost%20Time/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSand%20of%20Joshua.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Sand of Joshua.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%209/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FMidnight%20Blue%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Midnight Blue Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Fairuz/Fairuz%20Instrumental%203/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FAna%20LA%20Habibi%20Ood.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Ana LA Habibi Ood.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Schwarz%20%26%20Funk/Sapphire%20Lounge/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2F5%20Am%20%20Schwarz%20%26%20Funk.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/5 Am Schwarz & Funk.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Mehmet%20F.%20Nal%C4%B1nc%C4%B1/Caf%C3%A9%20Anatolia%20-%20Nev%20Bahar%20(New%20Spring)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FAy%20Du%CC%88s%CC%A7u%CC%88nce%20Mehmet%20F.%20Nal%C4%B1nc%C4%B1.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Ay Düşünce Mehmet F. Nalıncı.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Naseer%20Shamma/Hilal%20%2F%20Crescent%20Moon%20%2F%20Luna%20Creciente/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FHalat%20Wayd%20%20%20In%20A%20State%20Of%20Passion%20%20%20Estado%20De%20Pasio%CC%81n.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Halat Wayd In A State Of Passion Estado De Pasión.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Omid%20Shabani/Blooming%20in%20the%20Valley/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FBlooming%20in%20the%20Valley%20-%20Omid%20Shabani.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Blooming in the Valley - Omid Shabani.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Feras%20Charestan/Sound%20from%20the%20North/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FNawa%20Feras%20Charestan.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Nawa Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Ahmad%20Al%20Khatib/Sada/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FGhurbeh%20(Diaspora)%20-%20Ahmad%20Al%20Khatib.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Ghurbeh (Diaspora) - Ahmad Al Khatib.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Feras%20Charestan/Sama/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FMy%20Quiet%20Room%20Feras%20Charestan.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/My Quiet Room Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%209/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FBlue%20Pacific%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Blue Pacific Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Anthony%20Hathaway/Sorry/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSorry%20-%20Spa%20Version%20-%20Anthony%20Hathaway.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Sorry - Spa Version - Anthony Hathaway.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Feras%20Charestan/Ana/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FAna%20Feras%20Charestan.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Ana Feras Charestan.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Haytham%20Safia/Two%20Elements/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FAlief.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Alief.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%202/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FCruisin%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Cruisin Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Jacob%20Gurevitsch/Lovers%20in%20Paris%20(Deluxe)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FRiver%20of%20Pouyade%20Jacob%20Gurevitsch.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/River of Pouyade Jacob Gurevitsch.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%202/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FTwilight%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Twilight Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Thierry%20David/Serenity/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSearching%20Serenity.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Searching Serenity.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Abderraouf%20Ouertani/Contes%20d'un%20mis%C3%A9rable%20luth/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FConte%20d'un%20luth%20amoureux%20-%20Abderraouf%20Ouertani.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Conte d'un luth amoureux - Abderraouf Ouertani.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Milchbar%20Seaside%20Season%209%20(Deluxe%20Edition)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FMy%20Island%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/My Island Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Joseph%20Tawadros/The%20Bluebird%2C%20the%20Mystic%20and%20the%20Fool/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FLittle%20Bird%20Joseph%20Tawadros.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Little Bird Joseph Tawadros.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Le%20Trio%20Joubran/The%20Long%20March/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FThe%20Trees%20We%20Wear%20-%20Le%20Trio%20Joubran.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/The Trees We Wear - Le Trio Joubran.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Cairo%20Steps%2CBasem%20Darwisch%2CDiwan%20Caf%C3%A9/Oud%20Suite/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FOud%20Suite.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Oud Suite.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Tahta%20Menezes/%D9%84%D9%84%D8%B1%D8%A7%D8%AD%D8%A9/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2F%D9%84%D9%84%D8%B1%D8%A7%D8%AD%D8%A9%20Tahta%20Menezes%2C%20Rabih%20Abou-Khalil%2C%20Omar%20Faruk%20Tekbilek.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/للراحة Tahta Menezes, Rabih Abou-Khalil, Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Aytac%20Dogan/Kanun%20Resitali%203%20-%20Sezen%20Aksu%20%C5%9Eark%C4%B1lar%C4%B1%20(Live)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FTakvim%20-%20Live.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Takvim - Live.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Karim%20Baggili%2CLe%20Trio%20Joubran/Kali%20City/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSilent%20Stories%20-%20Instrumentale.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Silent Stories - Instrumentale.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Om-Official/Winds%20of%20Change%20(Reprise)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FWinds%20of%20Change%20(Reprise)%20-%20Om-Official.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Winds of Change (Reprise) - Om-Official.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%207/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FWalking%20on%20Sunshine%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Walking on Sunshine Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%204/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FChilled%20Cream%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Chilled Cream Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Ali%20Sabah/Sabah/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FFadfada%20Ali%20Sabah.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Fadfada Ali Sabah.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%207/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FPuerta%20del%20Sol%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Puerta del Sol Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Aarif%20Jaman/Oud%20(The%20Legend%20of%20Arabic%20Music)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FHijaz%20-%20Aarif%20Jaman.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Hijaz - Aarif Jaman.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%203/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FThe%20Palms%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/The Palms Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Jacob%20Gurevitsch/Milchbar%20-%20Seaside%20Season%2014/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSong%20for%20Sol%20%20Jacob%20Gurevitsch.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Song for Sol Jacob Gurevitsch.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Brian%20Keane%2C%20Omar%20Faruk%20Tekbilek/Fire%20Dance/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FDesert%20Twilight%20-%20Brian%20Keane%2C%20Omar%20Faruk%20Tekbilek.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Desert Twilight - Brian Keane, Omar Faruk Tekbilek.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Yasser%20Farouk/Sad%20Nay%20%26%20Oud/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FArabic%20Oud.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Arabic Oud.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Cairo%20Steps%2CDiwan%20Caf%C3%A9%2CRagy%20Kamal/El%20Zaitoun%20(Kanoun)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FEl%20Zaitoun.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/El Zaitoun.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%204/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FSmooth%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Smooth Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Oum/Dakchi%20Live%20in%20Marrakech/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FIntidhar%20-%20Live%20in%20Marrakech%20Oum.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Intidhar - Live in Marrakech Oum.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Milchbar%20-%20Seaside%20Season%207%20(Deluxe%20Edition)/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FMilchbar%201000%20AM%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Milchbar 1000 AM Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Blank%20%26%20Jones/Relax%20Edition%202/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FCity%20of%20Angels%20%20Blank%20%26%20Jones.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/City of Angels Blank & Jones.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Bliss/Afterlife/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FLost%20Soul%20Bliss.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Lost Soul Bliss.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: ALBUMART /albumart?cacheid=797&web=Thierry%20David/The%20Contemplative%20Journey/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20day%2FColor%20Verde.mp3&metadata=false Sep 17 13:24:25 lobby volumio[12723]: info: URI /mnt/INTERNAL/1 day/Color Verde.mp3 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushQueue Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::saveQueue Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::updateTrackBlock Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrackBlock Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPlay Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::play index 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::play index undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::startPlaybackTimer Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3 Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand stop took 7 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 17 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand clear took 16 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 10 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 7 milliseconds Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3" Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: No code Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 104ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 96ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 93ms Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 134ms Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand add "INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3" took 128 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 13ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 11ms Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand play Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:25 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 39ms Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand play took 34 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 34ms Sep 17 13:24:25 lobby volumio[12723]: info: ------------------------------ 32ms Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 20 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 19 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 11 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 9 milliseconds Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- Client requests Volumio play at index 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::volumioPlay Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::play index 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::stop Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::play index undefined Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::startPlaybackTimer Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3 Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: Sep 17 13:24:25 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand stop took 21 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 16 milliseconds Sep 17 13:24:25 lobby volumio[12723]: info: sendMpdCommand status took 12 milliseconds Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:25 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":61,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peaceful Night in Gaza","artist":"Sary Hany","album":"Gaza Surf Club (Original Motion Picture Soundtrack)","uri":"INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3","trackType":"mp3"} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:25 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:25 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":61,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peaceful Night in Gaza","artist":"Sary Hany","album":"Gaza Surf Club (Original Motion Picture Soundtrack)","uri":"INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3","trackType":"mp3"} Sep 17 13:24:25 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:25 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:25 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:25 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":61,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peaceful Night in Gaza","artist":"Sary Hany","album":"Gaza Surf Club (Original Motion Picture Soundtrack)","uri":"INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":61,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peaceful Night in Gaza","artist":"Sary Hany","album":"Gaza Surf Club (Original Motion Picture Soundtrack)","uri":"INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 133ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 161ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 153ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 147ms Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 110 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand clear took 101 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 100 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 11 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 8 milliseconds Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3" Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 17 13:24:26 lobby volumio[12723]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 0 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 0 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::play index undefined Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 169ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 166ms Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::startPlaybackTimer Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1 day/Egyptian Nile.mp3 Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 81ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 80ms Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: options Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 139 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 143ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 136 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand add "INTERNAL/1 day/Peaceful Night in Gaza Sary Hany.mp3" took 137 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand stop took 73 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 47ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 44 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 44ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 42 milliseconds Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand play Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 238ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 353ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 142ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 139ms Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:26 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 122ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand play took 115 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand clear took 115 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 21ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 20ms Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/1 day/Egyptian Nile.mp3" Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: error: updateQueue error: null Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 96ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 93 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand add "INTERNAL/1 day/Egyptian Nile.mp3" took 92 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 91ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 88 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 88ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 86 milliseconds Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand play Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: No code Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 202ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 200ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 199ms Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces system playlist update Sep 17 13:24:26 lobby volumio[12723]: info: Ignoring MPD Status Update Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 135ms Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand play took 130 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 19ms Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 17ms Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: Sep 17 13:24:26 lobby volumio[12723]: ---------------------------- MPD announces state update: player Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::getState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand status Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 33 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 35 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 32 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand status took 30 milliseconds Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseState Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 17 13:24:26 lobby volumio[12723]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: verbose: ControllerMpd::parseTrackInfo Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Egyptian Nile","artist":"Yasser Farouk","album":"Arabic Music","uri":"INTERNAL/1 day/Egyptian Nile.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus stop Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Egyptian Nile","artist":"Yasser Farouk","album":"Arabic Music","uri":"INTERNAL/1 day/Egyptian Nile.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Egyptian Nile","artist":"Yasser Farouk","album":"Arabic Music","uri":"INTERNAL/1 day/Egyptian Nile.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ControllerMpd::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::servicePushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Egyptian Nile","artist":"Yasser Farouk","album":"Arabic Music","uri":"INTERNAL/1 day/Egyptian Nile.mp3","trackType":"mp3"} Sep 17 13:24:26 lobby volumio[12723]: verbose: CURRENT POSITION 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState stateService play Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::syncState currentStatus play Sep 17 13:24:26 lobby volumio[12723]: info: Received an update from plugin. extracting info from payload Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: CoreStateMachine::pushState Sep 17 13:24:26 lobby volumio[12723]: info: CorePlayQueue::getTrack 1 Sep 17 13:24:26 lobby volumio[12723]: info: CoreCommandRouter::volumioPushState Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 113ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 142ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 136ms Sep 17 13:24:26 lobby volumio[12723]: info: ------------------------------ 135ms Sep 17 13:24:30 lobby volumio[12723]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 13:24:30 lobby volumio[12723]: Error [ERR_TLS_CERT_ALTNAME_INVALID]: Hostname/IP does not match certificate's altnames: Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me Sep 17 13:24:30 lobby volumio[12723]: at new NodeError (internal/errors.js:322:7) Sep 17 13:24:30 lobby volumio[12723]: at Object.checkServerIdentity (tls.js:347:12) Sep 17 13:24:30 lobby volumio[12723]: at TLSSocket.onConnectSecure (_tls_wrap.js:1525:27) Sep 17 13:24:30 lobby volumio[12723]: at TLSSocket.emit (events.js:400:28) Sep 17 13:24:30 lobby volumio[12723]: at TLSSocket._finishInit (_tls_wrap.js:937:8) Sep 17 13:24:30 lobby volumio[12723]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:709:12) { Sep 17 13:24:30 lobby volumio[12723]: reason: "Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me", Sep 17 13:24:30 lobby volumio[12723]: host: 'lastfm.freetls.fastly.net', Sep 17 13:24:30 lobby volumio[12723]: cert: { Sep 17 13:24:30 lobby volumio[12723]: subject: [Object: null prototype] { CN: 'retail.soundyoucanfeel.me' }, Sep 17 13:24:30 lobby volumio[12723]: issuer: [Object: null prototype] { C: 'US', O: "Let's Encrypt", CN: 'E6' }, Sep 17 13:24:30 lobby volumio[12723]: subjectaltname: 'DNS:retail.soundyoucanfeel.me', Sep 17 13:24:30 lobby volumio[12723]: infoAccess: [Object: null prototype] { 'CA Issuers - URI': [Array] }, Sep 17 13:24:30 lobby volumio[12723]: bits: 384, Sep 17 13:24:30 lobby volumio[12723]: pubkey: , Sep 17 13:24:30 lobby volumio[12723]: asn1Curve: 'secp384r1', Sep 17 13:24:30 lobby volumio[12723]: nistCurve: 'P-384', Sep 17 13:24:30 lobby volumio[12723]: valid_from: 'Jul 20 21:30:07 2025 GMT', Sep 17 13:24:30 lobby volumio[12723]: valid_to: 'Oct 18 21:30:06 2025 GMT', Sep 17 13:24:30 lobby volumio[12723]: fingerprint: '8C:A7:76:D0:C4:BF:70:25:5B:B4:50:82:FD:11:36:5C:00:5D:CE:17', Sep 17 13:24:30 lobby volumio[12723]: fingerprint256: 'FE:FF:AE:A8:BD:03:D3:7B:FC:F6:A2:28:07:07:45:6D:EA:76:CF:1C:CE:2E:F3:45:B8:2D:11:F3:C3:FD:FF:84', Sep 17 13:24:30 lobby volumio[12723]: ext_key_usage: [ '1.3.6.1.5.5.7.3.1', '1.3.6.1.5.5.7.3.2' ], Sep 17 13:24:30 lobby volumio[12723]: serialNumber: '05137B5E6680AD63167410D9A843A7CF43C6', Sep 17 13:24:30 lobby volumio[12723]: raw: , Sep 17 13:24:30 lobby volumio[12723]: issuerCertificate: { Sep 17 13:24:30 lobby volumio[12723]: subject: [Object: null prototype], Sep 17 13:24:30 lobby volumio[12723]: issuer: [Object: null prototype], Sep 17 13:24:30 lobby volumio[12723]: infoAccess: [Object: null prototype], Sep 17 13:24:30 lobby volumio[12723]: bits: 384, Sep 17 13:24:30 lobby volumio[12723]: pubkey: , Sep 17 13:24:30 lobby volumio[12723]: asn1Curve: 'secp384r1', Sep 17 13:24:30 lobby volumio[12723]: nistCurve: 'P-384', Sep 17 13:24:30 lobby volumio[12723]: valid_from: 'Mar 13 00:00:00 2024 GMT', Sep 17 13:24:30 lobby volumio[12723]: valid_to: 'Mar 12 23:59:59 2027 GMT', Sep 17 13:24:30 lobby volumio[12723]: fingerprint: 'C9:4D:C4:83:1A:90:1A:9F:EC:0F:B4:9B:71:BD:49:B5:AA:D4:FA:D0', Sep 17 13:24:30 lobby volumio[12723]: fingerprint256: '76:E9:E2:88:AA:FC:0E:37:F4:39:0C:BF:94:6A:AD:99:7D:5C:1C:90:1B:3C:E5:13:D3:D8:FA:DB:AB:E2:AB:85', Sep 17 13:24:30 lobby volumio[12723]: ext_key_usage: [Array], Sep 17 13:24:30 lobby volumio[12723]: serialNumber: 'B0573E9173972770DBB487CB3A452B38', Sep 17 13:24:30 lobby volumio[12723]: raw: , Sep 17 13:24:30 lobby volumio[12723]: issuerCertificate: [Object] Sep 17 13:24:30 lobby volumio[12723]: } Sep 17 13:24:30 lobby volumio[12723]: }, Sep 17 13:24:30 lobby volumio[12723]: code: 'ERR_TLS_CERT_ALTNAME_INVALID' Sep 17 13:24:30 lobby volumio[12723]: } Sep 17 13:24:30 lobby volumio[12723]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 13:24:30 lobby ovpn-vpn0[10626]: event_wait : Interrupted system call (code=4) Sep 17 13:24:30 lobby systemd[1]: Stopping OpenVPN connection to vpn0... Sep 17 13:24:30 lobby systemd[1]: openvpn.service: Succeeded. Sep 17 13:24:30 lobby systemd[1]: Stopped OpenVPN service. Sep 17 13:24:30 lobby systemd[1]: Stopping OpenVPN service... Sep 17 13:24:30 lobby systemd[1]: Starting OpenVPN service... Sep 17 13:24:30 lobby systemd[1]: Started OpenVPN service. Sep 17 13:24:30 lobby CRON[12690]: (CRON) info (No MTA installed, discarding output) Sep 17 13:24:30 lobby CRON[12690]: pam_unix(cron:session): session closed for user root Sep 17 13:24:30 lobby ovpn-vpn0[10626]: Closing TUN/TAP interface Sep 17 13:24:30 lobby ovpn-vpn0[10626]: /sbin/ip addr del dev tun0 10.10.0.60/22 Sep 17 13:24:30 lobby ovpn-vpn0[10626]: SIGTERM[hard,] received, process exiting Sep 17 13:24:30 lobby systemd[1]: openvpn@vpn0.service: Succeeded. Sep 17 13:24:30 lobby systemd[1]: Stopped OpenVPN connection to vpn0. Sep 17 13:24:30 lobby systemd[1]: Starting OpenVPN connection to vpn0... Sep 17 13:24:30 lobby ovpn-vpn0[13087]: DEPRECATED OPTION: --max-routes option ignored.The number of routes is unlimited as of OpenVPN 2.4. This option will be removed in a future version, please remove it from your configuration. Sep 17 13:24:30 lobby ovpn-vpn0[13087]: OpenVPN 2.4.7 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Apr 28 2021 Sep 17 13:24:30 lobby ovpn-vpn0[13087]: library versions: OpenSSL 1.1.1n 15 Mar 2022, LZO 2.10 Sep 17 13:24:30 lobby systemd[1]: Started OpenVPN connection to vpn0. Sep 17 13:24:30 lobby ovpn-vpn0[13087]: Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Sep 17 13:24:30 lobby ovpn-vpn0[13087]: Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Sep 17 13:24:30 lobby ovpn-vpn0[13087]: TCP/UDP: Preserving recently used remote address: [AF_INET]140.82.7.94:1194 Sep 17 13:24:30 lobby ovpn-vpn0[13087]: Attempting to establish TCP connection with [AF_INET]140.82.7.94:1194 [nonblock] Sep 17 13:24:31 lobby sudo[13089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 13:23 Sep 17 13:24:31 lobby sudo[13089]: 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="eb7109a0318f43be1cdb19572cb80f7f163a915d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Чт 01 фев 2024 20:49:33 MSK" VOLUMIO_VERSION="3.0" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="22506d91c2deba7d4d5e58d82babad9f"