-- Logs begin at Sun 2024-12-01 19:10:46 CST, end at Sun 2024-12-01 19:24:41 CST. -- Dec 01 19:23:14 volumio ntpd[726]: receive: Unexpected origin timestamp 0xeaf6c308.709d86d6 does not match aorg 0000000000.00000000 from server@202.118.1.130 xmt 0xeaf6c5a2.d5898e82 Dec 01 19:23:14 volumio ntpd[726]: receive: Unexpected origin timestamp 0xeaf6c308.709b79cf does not match aorg 0000000000.00000000 from server@84.16.67.12 xmt 0xeaf6c5a2.ea2b10f6 Dec 01 19:23:15 volumio ntpd[726]: receive: Unexpected origin timestamp 0xeaf6c308.709ec364 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeaf6c5a2.e98d475c Dec 01 19:23:16 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Dec 01 19:23:16 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: info: Retrieving Cloud Streaming UI Dec 01 19:23:18 volumio volumio[759]: info: Getting Tidal Cloud Configuration Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: info: Getting Qobuz Cloud Configuration Dec 01 19:23:18 volumio volumio[759]: info: Asking plugin for UI Config Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: info: Getting Spotify Cloud Configuration Dec 01 19:23:18 volumio volumio[759]: info: Asking plugin for UI Config Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: info: Saving Spotify Acccount Dec 01 19:23:18 volumio volumio[759]: info: Got Tidal Cloud Configuration Dec 01 19:23:18 volumio volumio[759]: info: Got it Dec 01 19:23:18 volumio volumio[759]: info: Got it Dec 01 19:23:18 volumio volumio[759]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 19:23:18 volumio volumio[759]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:18 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 19:23:22 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:26 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:30 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:34 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:38 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:42 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:46 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:50 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 01 19:23:52 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/iStoreOS Dec 01 19:23:52 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 19:23:52 volumio systemd[1]: mnt-NAS-iStoreOS.mount: Succeeded. Dec 01 19:23:52 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Dec 01 19:23:52 volumio volumio[759]: info: Share iStoreOS successfully unmounted Dec 01 19:23:52 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.75/iStoreOS\\music /mnt/NAS/iStoreOS Dec 01 19:23:52 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 19:23:52 volumio kernel: CIFS: Attempting to mount \\192.168.0.75\iStoreOS Dec 01 19:23:52 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 8 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 6 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 6 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: 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} Dec 01 19:23:52 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: No code Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: 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} Dec 01 19:23:52 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: No code Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: 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} Dec 01 19:23:52 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: No code Dec 01 19:23:52 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:52 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:52 volumio volumio[759]: info: ------------------------------ 140ms Dec 01 19:23:52 volumio volumio[759]: info: ------------------------------ 139ms Dec 01 19:23:52 volumio volumio[759]: info: ------------------------------ 138ms Dec 01 19:23:52 volumio volumio[759]: info: [1733052232926] Database update started Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: Dec 01 19:23:52 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:23:52 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 26 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 26 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:23:52 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:23:52 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:52 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:53 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:23:53 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:23:53 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: 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} Dec 01 19:23:53 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: No code Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: 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} Dec 01 19:23:53 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: No code Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: 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} Dec 01 19:23:53 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: No code Dec 01 19:23:53 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:23:53 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:23:53 volumio volumio[759]: info: ------------------------------ 145ms Dec 01 19:23:53 volumio volumio[759]: info: ------------------------------ 122ms Dec 01 19:23:53 volumio volumio[759]: info: ------------------------------ 121ms Dec 01 19:23:53 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 19:23:54 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:23:58 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:24:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:24:03 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 01 19:24:03 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/iStoreOS Dec 01 19:24:03 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 19:24:03 volumio systemd[1]: mnt-NAS-iStoreOS.mount: Succeeded. Dec 01 19:24:03 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Dec 01 19:24:03 volumio volumio[759]: info: Share iStoreOS successfully unmounted Dec 01 19:24:03 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.75/iStoreOS\\music /mnt/NAS/iStoreOS Dec 01 19:24:03 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 19:24:03 volumio kernel: CIFS: Attempting to mount \\192.168.0.75\iStoreOS Dec 01 19:24:03 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: info: Dec 01 19:24:03 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:03 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: info: Dec 01 19:24:03 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:03 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: info: Dec 01 19:24:03 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:03 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:03 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:03 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 122ms Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 120ms Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 119ms Dec 01 19:24:04 volumio volumio[759]: info: [1733052244104] Database update started Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: info: Dec 01 19:24:04 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: info: Dec 01 19:24:04 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: info: Dec 01 19:24:04 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 26 milliseconds Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 25 milliseconds Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:04 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:04 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: 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} Dec 01 19:24:04 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: No code Dec 01 19:24:04 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:04 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 136ms Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 114ms Dec 01 19:24:04 volumio volumio[759]: info: ------------------------------ 112ms Dec 01 19:24:04 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand rescan Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand rescan took 3 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: 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} Dec 01 19:24:06 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: No code Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: 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} Dec 01 19:24:06 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: No code Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: 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} Dec 01 19:24:06 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: No code Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: ------------------------------ 135ms Dec 01 19:24:06 volumio volumio[759]: info: ------------------------------ 135ms Dec 01 19:24:06 volumio volumio[759]: info: ------------------------------ 134ms Dec 01 19:24:06 volumio volumio[759]: info: MPD Database updated - AlbumList cache refreshed Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: database Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: MPD Database updated - AlbumList cache refreshed Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: database Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: MPD Database updated - AlbumList cache refreshed Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: database Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: Dec 01 19:24:06 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 11 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 11 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 11 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 10 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 9 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 9 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 7 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 8 milliseconds Dec 01 19:24:06 volumio volumio[759]: info: sendMpdCommand status took 7 milliseconds Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:06 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: 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} Dec 01 19:24:06 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:06 volumio volumio[759]: info: No code Dec 01 19:24:06 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:06 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:06 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: 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} Dec 01 19:24:07 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: No code Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: 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} Dec 01 19:24:07 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: No code Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: 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} Dec 01 19:24:07 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: No code Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: 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} Dec 01 19:24:07 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: No code Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: 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} Dec 01 19:24:07 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: No code Dec 01 19:24:07 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:07 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 159ms Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 159ms Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 158ms Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 158ms Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 157ms Dec 01 19:24:07 volumio volumio[759]: info: ------------------------------ 156ms Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand update Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: info: Dec 01 19:24:07 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand update took 2 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: info: Dec 01 19:24:07 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: info: Dec 01 19:24:07 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:07 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:07 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:07 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:07 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:07 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:07 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 114ms Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 114ms Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 112ms Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: info: Dec 01 19:24:08 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: info: Dec 01 19:24:08 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: info: Dec 01 19:24:08 volumio volumio[759]: ---------------------------- MPD announces state update: update Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::getState Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 21 milliseconds Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 20 milliseconds Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:24:08 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Dec 01 19:24:08 volumio volumio[759]: verbose: ControllerMpd::parseState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ControllerMpd::pushState Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::servicePushState Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: 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} Dec 01 19:24:08 volumio volumio[759]: verbose: CURRENT POSITION 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: No code Dec 01 19:24:08 volumio volumio[759]: info: CoreStateMachine::pushState Dec 01 19:24:08 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:08 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 131ms Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 113ms Dec 01 19:24:08 volumio volumio[759]: info: ------------------------------ 113ms Dec 01 19:24:10 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Dec 01 19:24:10 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:15 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:15 volumio volumio[759]: info: CURURI: playlists Dec 01 19:24:15 volumio volumio[759]: info: Listing playlists Dec 01 19:24:15 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:17 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:17 volumio volumio[759]: info: CURURI: music-library Dec 01 19:24:17 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:19 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:19 volumio volumio[759]: info: CURURI: music-library/NAS Dec 01 19:24:19 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:19 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:19 volumio volumio[759]: info: CURURI: music-library/NAS/iStoreOS Dec 01 19:24:19 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:21 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:21 volumio volumio[759]: info: CURURI: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer] Dec 01 19:24:21 volumio volumio[759]: error: Cue Parser - Cannot parse NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue Dec 01 19:24:21 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:22 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:22 volumio volumio[759]: info: CURURI: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue Dec 01 19:24:22 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0001 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0002 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0003 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0004 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0005 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0006 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0007 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0008 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0009 Dec 01 19:24:22 volumio volumio[759]: info: Preloading song: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0010 Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0001 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0001' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0002 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0002' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0003 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0003' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0004 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0004' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0005 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0005' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0006 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0006' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0007 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0007' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0008 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0008' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0009 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0009' Dec 01 19:24:22 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0010 in service mpd Dec 01 19:24:22 volumio volumio[759]: error: scanFolder - failure to stat '/mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0010' Dec 01 19:24:24 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:24 volumio volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::ClearQueue Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::stop Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::clearPlayQueue Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::saveQueue Dec 01 19:24:24 volumio volumio[759]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::addQueueItems Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::addQueueItems Dec 01 19:24:24 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0001 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0001 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0002 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0002 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0003 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0003 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0004 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0004 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0005 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0005 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0006 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0006 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0007 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0007 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0008 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0008 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0009 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0009 Dec 01 19:24:24 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0010 Dec 01 19:24:24 volumio volumio[759]: info: Using cached record of: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue/track0010 Dec 01 19:24:24 volumio volumio[759]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::saveQueue Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::updateTrackBlock Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::getTrackBlock Dec 01 19:24:24 volumio volumio[759]: info: CoreCommandRouter::volumioPlay Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::play index 9 Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::stop Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::play index undefined Dec 01 19:24:24 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:24 volumio volumio[759]: info: CorePlayQueue::getTrack 9 Dec 01 19:24:27 volumio volumio[759]: info: CoreCommandRouter::volumioPlay Dec 01 19:24:27 volumio volumio[759]: info: CoreStateMachine::play index undefined Dec 01 19:24:27 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:27 volumio volumio[759]: info: CorePlayQueue::getTrack 0 Dec 01 19:24:34 volumio dhcpcd[624]: eth0: hardware address 04:d4:c4:37:c5:00 claims 192.168.0.188 Dec 01 19:24:37 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:37 volumio volumio[759]: info: CURURI: music-library Dec 01 19:24:37 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:38 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:38 volumio volumio[759]: info: CURURI: music-library/NAS Dec 01 19:24:38 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:39 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:24:39 volumio volumio[759]: info: CURURI: music-library/NAS/iStoreOS Dec 01 19:24:40 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:41 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:41 volumio volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 19:24:41 volumio volumio[759]: info: CoreStateMachine::ClearQueue Dec 01 19:24:41 volumio volumio[759]: info: CoreStateMachine::stop Dec 01 19:24:41 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:24:41 volumio volumio[759]: info: CorePlayQueue::clearPlayQueue Dec 01 19:24:41 volumio volumio[759]: info: CorePlayQueue::saveQueue Dec 01 19:24:41 volumio volumio[759]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:24:41 volumio volumio[759]: info: CoreStateMachine::addQueueItems Dec 01 19:24:41 volumio volumio[759]: info: CorePlayQueue::addQueueItems Dec 01 19:24:41 volumio volumio[759]: info: Preload queue cleared Dec 01 19:24:41 volumio volumio[759]: info: Adding Item to queue: music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer] Dec 01 19:24:41 volumio volumio[759]: info: Exploding uri music-library/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer] in service mpd Dec 01 19:24:41 volumio volumio[759]: info: ALBUMART /albumart?cacheid=470&web=//extralarge&path=%2Fmnt%2FNAS%2FiStoreOS%2F1987-Summer_Romance_87%5B%E6%96%B0%E5%AE%9D%E8%89%BA%5D%5BKorer%5D%2FCDImage.ape&metadata=false Dec 01 19:24:41 volumio volumio[759]: info: URI /mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.ape Dec 01 19:24:41 volumio volumio[759]: info: ALBUMART /albumart?cacheid=470&web=%3F%C5%B9%3F%3F%3F/Summer%20Romance'87.%3F%3F%3F%D5%B1%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FiStoreOS%2F1987-Summer_Romance_87%5B%E6%96%B0%E5%AE%9D%E8%89%BA%5D%5BKorer%5D%2FCDImage.cue&metadata=false Dec 01 19:24:41 volumio volumio[759]: info: URI /mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue Dec 01 19:24:41 volumio volumio[759]: info: ALBUMART /albumart?cacheid=470&web=%3F%C5%B9%3F%3F%3F/Summer%20Romance'87.%3F%3F%3F%D5%B1%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FiStoreOS%2F1987-Summer_Romance_87%5B%E6%96%B0%E5%AE%9D%E8%89%BA%5D%5BKorer%5D%2FCDImage.cue&metadata=false Dec 01 19:24:41 volumio volumio[759]: info: URI /mnt/NAS/iStoreOS/1987-Summer_Romance_87[新宝艺][Korer]/CDImage.cue Dec 01 19:24:41 volumio volumio[759]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 19:24:41 volumio volumio[759]: Error: Unable to resolve or reject the same promise twice Dec 01 19:24:41 volumio volumio[759]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 01 19:24:41 volumio volumio[759]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Dec 01 19:24:41 volumio volumio[759]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 01 19:24:41 volumio volumio[759]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 01 19:24:41 volumio volumio[759]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 01 19:24:41 volumio volumio[759]: at Socket.emit (events.js:315:20) Dec 01 19:24:41 volumio volumio[759]: at addChunk (internal/streams/readable.js:309:12) Dec 01 19:24:41 volumio volumio[759]: at readableAddChunk (internal/streams/readable.js:280:11) Dec 01 19:24:41 volumio volumio[759]: at Socket.Readable.push (internal/streams/readable.js:223:10) Dec 01 19:24:41 volumio volumio[759]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 01 19:24:41 volumio volumio[759]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 19:24:41 volumio sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 19:23 Dec 01 19:24:41 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"