-- Logs begin at Fri 2013-04-12 00:01:06 UTC, end at Wed 2026-10-14 02:17:23 UTC. -- Oct 14 02:16:12 volumio sudo[2908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 02:16:12 volumio sudo[2908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 02:16:12 volumio sudo[2908]: pam_unix(sudo:session): session closed for user root Oct 14 02:16:12 volumio sudo[2910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 02:16:12 volumio sudo[2910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 02:16:12 volumio sudo[2910]: pam_unix(sudo:session): session closed for user root Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 14 02:16:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Oct 14 02:16:13 volumio volumio[1142]: info: CoreStateMachine::getQueue Oct 14 02:16:13 volumio volumio[1142]: info: CorePlayQueue::getQueue Oct 14 02:16:13 volumio volumio[1142]: info: Listing playlists Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 02:16:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 02:16:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 02:16:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 02:16:22 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:16:22 volumio volumio[1142]: info: CURURI: playlists Oct 14 02:16:22 volumio volumio[1142]: info: Listing playlists Oct 14 02:16:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:16:24 volumio volumio[1142]: info: CURURI: playlists/Nas Oct 14 02:16:26 volumio volumio[1142]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:16:26 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Oct 14 02:16:26 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Oct 14 02:16:26 volumio volumio[1142]: info: Adding Item to queue: upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@1$4 Oct 14 02:16:26 volumio volumio[1142]: info: Exploding uri upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@1$4 in service upnp_browser Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::saveQueue Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Oct 14 02:16:27 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::play index 0 Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:27 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:27 volumio volumio[1142]: info: [1791944187638] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand stop took 8 milliseconds Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:16:27 volumio volumio[1142]: info: Oct 14 02:16:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:27 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/11442.wav" Oct 14 02:16:27 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:16:27 volumio volumio[1142]: info: ------------------------------ 6ms Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11442.wav" Oct 14 02:16:27 volumio volumio[1142]: info: Oct 14 02:16:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:27 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11442.wav" took 1 milliseconds Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:16:27 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:16:27 volumio volumio[1142]: info: Oct 14 02:16:27 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:27 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:27 volumio volumio[1142]: info: Oct 14 02:16:27 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:16:27 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:27 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:27 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:27 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:27 volumio volumio[1142]: info: ------------------------------ 12ms Oct 14 02:16:27 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 02:16:27 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:27 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:27 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:27 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":232,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:27 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:16:27 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:27 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:27 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:27 volumio volumio[1142]: info: ------------------------------ 13ms Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Oct 14 02:16:28 volumio volumio[1142]: info: Adding Item to queue: upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@1$4 Oct 14 02:16:28 volumio volumio[1142]: info: Exploding uri upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@1$4 in service upnp_browser Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::saveQueue Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Oct 14 02:16:28 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::play index 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:28 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::serviceStop Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Oct 14 02:16:28 volumio volumio[1142]: info: [1791944188558] ControllerUPNPBrowser::stop Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:16:28 volumio volumio[1142]: info: Oct 14 02:16:28 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand stop took 36 milliseconds Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: [1791944188596] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand stop took 2 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:16:28 volumio volumio[1142]: info: Oct 14 02:16:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:28 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand clear took 1 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/11442.wav" Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:28 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: No code Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: ------------------------------ 8ms Oct 14 02:16:28 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:16:28 volumio volumio[1142]: info: ------------------------------ 6ms Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11442.wav" Oct 14 02:16:28 volumio volumio[1142]: info: Oct 14 02:16:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:28 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11442.wav" took 0 milliseconds Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:16:28 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:16:28 volumio volumio[1142]: info: Oct 14 02:16:28 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:28 volumio volumio[1142]: info: Oct 14 02:16:28 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:28 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: ------------------------------ 7ms Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:28 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:16:28 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:28 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:28 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:28 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:16:28 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:28 volumio volumio[1142]: info: ------------------------------ 9ms Oct 14 02:16:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:16:33 volumio volumio[1142]: info: CURURI: playlists/纯音乐 Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:34 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:39 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:16:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:16:41 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:16:42 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Oct 14 02:16:44 volumio volumio[1142]: info: Adding Item to queue: upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@64$1 Oct 14 02:16:44 volumio volumio[1142]: info: Exploding uri upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@64$1 in service upnp_browser Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::saveQueue Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Oct 14 02:16:44 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::play index 1000 Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:44 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::serviceStop Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Oct 14 02:16:44 volumio volumio[1142]: info: [1791944204876] ControllerUPNPBrowser::stop Oct 14 02:16:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:16:44 volumio volumio[1142]: info: Oct 14 02:16:44 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:44 volumio volumio[1142]: info: sendMpdCommand stop took 92 milliseconds Oct 14 02:16:44 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 1000 Oct 14 02:16:44 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:16:44 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:44 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:16:44 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:44 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11442.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11442.wav","trackType":"wav"} Oct 14 02:16:44 volumio volumio[1142]: verbose: CURRENT POSITION 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:44 volumio volumio[1142]: info: No code Oct 14 02:16:44 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:44 volumio volumio[1142]: info: ------------------------------ 9ms Oct 14 02:16:49 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:49 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:49 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:50 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:50 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:50 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:50 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:50 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 14 02:16:53 volumio volumio[1142]: info: CoreCommandRouter::volumioMoveQueue Oct 14 02:16:53 volumio volumio[1142]: info: CoreStateMachine::moveQueueItem 20 20 Oct 14 02:16:53 volumio volumio[1142]: info: CorePlayQueue::moveQueueItem 20 --> 20 Oct 14 02:16:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::volumioNext Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::next Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:55 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:16:55 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:16:55 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Oct 14 02:16:55 volumio volumio[1142]: info: [1791944215051] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Oct 14 02:16:55 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand stop took 2 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:16:55 volumio volumio[1142]: info: Oct 14 02:16:55 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:55 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand clear took 1 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/11465.wav" Oct 14 02:16:55 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:16:55 volumio volumio[1142]: info: ------------------------------ 0ms Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11465.wav" Oct 14 02:16:55 volumio volumio[1142]: info: Oct 14 02:16:55 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:16:55 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11465.wav" took 1 milliseconds Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:16:55 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand play took 0 milliseconds Oct 14 02:16:55 volumio volumio[1142]: info: Oct 14 02:16:55 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:55 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:55 volumio volumio[1142]: info: Oct 14 02:16:55 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:16:55 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:55 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:55 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Oct 14 02:16:55 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11465.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11465.wav","trackType":"wav"} Oct 14 02:16:55 volumio volumio[1142]: verbose: CURRENT POSITION 1 Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:55 volumio volumio[1142]: info: ------------------------------ 9ms Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand status took 8 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:16:55 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:16:55 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:16:55 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:16:55 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Oct 14 02:16:55 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11465.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11465.wav","trackType":"wav"} Oct 14 02:16:55 volumio volumio[1142]: verbose: CURRENT POSITION 1 Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:16:55 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:55 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:16:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:16:55 volumio volumio[1142]: info: ------------------------------ 12ms Oct 14 02:17:02 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:02 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:02 volumio volumio[1142]: info: CoreStateMachine::setRandom true Oct 14 02:17:02 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:02 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:02 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:04 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:04 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:04 volumio volumio[1142]: info: CoreStateMachine::setRandom false Oct 14 02:17:04 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:04 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:05 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:05 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:05 volumio volumio[1142]: info: CoreStateMachine::setRandom true Oct 14 02:17:05 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:05 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:05 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:06 volumio volumio[1142]: info: CoreStateMachine::setRandom false Oct 14 02:17:06 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:06 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:06 volumio volumio[1142]: info: CoreStateMachine::setRepeat true single false Oct 14 02:17:06 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:07 volumio volumio[1142]: info: CoreStateMachine::setRandom true Oct 14 02:17:07 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:07 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::setRepeat true single true Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::setRepeat true single true Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioRandom Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::writePlayerControls Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::setRepeat true single true Oct 14 02:17:10 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioNext Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::next Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::next Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand next Oct 14 02:17:11 volumio volumio[1142]: info: Oct 14 02:17:11 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand next took 60 milliseconds Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Oct 14 02:17:11 volumio volumio[1142]: 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} Oct 14 02:17:11 volumio volumio[1142]: verbose: CURRENT POSITION 1 Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::play index 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:11 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:17:11 volumio volumio[1142]: info: ------------------------------ 9ms Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: info: [1791944231188] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand stop took 2 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:17:11 volumio volumio[1142]: info: Oct 14 02:17:11 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:11 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/2483.wav" Oct 14 02:17:11 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:17:11 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/2483.wav" Oct 14 02:17:11 volumio volumio[1142]: info: Oct 14 02:17:11 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:11 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/2483.wav" took 1 milliseconds Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:17:11 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:17:11 volumio volumio[1142]: info: Oct 14 02:17:11 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:11 volumio volumio[1142]: info: Oct 14 02:17:11 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2483.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/2483.wav","trackType":"wav"} Oct 14 02:17:11 volumio volumio[1142]: verbose: CURRENT POSITION 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: ------------------------------ 9ms Oct 14 02:17:11 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 02:17:11 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:11 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:11 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:11 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2483.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/2483.wav","trackType":"wav"} Oct 14 02:17:11 volumio volumio[1142]: verbose: CURRENT POSITION 438 Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:11 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:11 volumio volumio[1142]: info: ------------------------------ 12ms Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioNext Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::next Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::next Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand next Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand next took 34 milliseconds Oct 14 02:17:13 volumio volumio[1142]: info: Oct 14 02:17:13 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 438 Oct 14 02:17:13 volumio volumio[1142]: 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} Oct 14 02:17:13 volumio volumio[1142]: verbose: CURRENT POSITION 438 Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::play index 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:13 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:17:13 volumio volumio[1142]: info: ------------------------------ 6ms Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: info: [1791944233473] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand stop took 3 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:17:13 volumio volumio[1142]: info: Oct 14 02:17:13 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:13 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand clear took 3 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/6711.wav" Oct 14 02:17:13 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:17:13 volumio volumio[1142]: info: ------------------------------ 2ms Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/6711.wav" Oct 14 02:17:13 volumio volumio[1142]: info: Oct 14 02:17:13 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:13 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/6711.wav" took 0 milliseconds Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:17:13 volumio volumio[1142]: info: ------------------------------ 1ms Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:17:13 volumio volumio[1142]: info: Oct 14 02:17:13 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:13 volumio volumio[1142]: info: Oct 14 02:17:13 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6711.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/6711.wav","trackType":"wav"} Oct 14 02:17:13 volumio volumio[1142]: verbose: CURRENT POSITION 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: ------------------------------ 10ms Oct 14 02:17:13 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 02:17:13 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:13 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:13 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:13 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6711.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/6711.wav","trackType":"wav"} Oct 14 02:17:13 volumio volumio[1142]: verbose: CURRENT POSITION 611 Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:13 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:13 volumio volumio[1142]: info: ------------------------------ 11ms Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioNext Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::next Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::next Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand next Oct 14 02:17:15 volumio volumio[1142]: info: Oct 14 02:17:15 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand next took 63 milliseconds Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 611 Oct 14 02:17:15 volumio volumio[1142]: 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} Oct 14 02:17:15 volumio volumio[1142]: verbose: CURRENT POSITION 611 Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::play index 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::stop Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:15 volumio volumio[1142]: verbose: UNSET VOLATILE Oct 14 02:17:15 volumio volumio[1142]: info: ------------------------------ 6ms Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::play index undefined Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: info: [1791944235601] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand stop took 2 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:17:15 volumio volumio[1142]: info: Oct 14 02:17:15 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:15 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/11460.wav" Oct 14 02:17:15 volumio volumio[1142]: error: updateQueue error: null Oct 14 02:17:15 volumio volumio[1142]: info: ------------------------------ 0ms Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11460.wav" Oct 14 02:17:15 volumio volumio[1142]: info: Oct 14 02:17:15 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Oct 14 02:17:15 volumio volumio[1142]: info: Ignoring MPD Status Update Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/11460.wav" took 2 milliseconds Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:17:15 volumio volumio[1142]: info: ------------------------------ 3ms Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:17:15 volumio volumio[1142]: info: Oct 14 02:17:15 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:15 volumio volumio[1142]: info: Oct 14 02:17:15 volumio volumio[1142]: ---------------------------- MPD announces state update: player Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::getState Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::parseState Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":301,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11460.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11460.wav","trackType":"wav"} Oct 14 02:17:15 volumio volumio[1142]: verbose: CURRENT POSITION 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: ------------------------------ 8ms Oct 14 02:17:15 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 02:17:15 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:17:15 volumio volumio[1142]: info: ControllerMpd::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Oct 14 02:17:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 237 Oct 14 02:17:15 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11460.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/11460.wav","trackType":"wav"} Oct 14 02:17:15 volumio volumio[1142]: verbose: CURRENT POSITION 237 Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:17:15 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: CoreStateMachine::pushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Oct 14 02:17:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:17:15 volumio volumio[1142]: info: ------------------------------ 11ms Oct 14 02:17:20 volumio volumio[1142]: info: CoreCommandRouter::volumioMoveQueue Oct 14 02:17:20 volumio volumio[1142]: info: CoreStateMachine::moveQueueItem 41 41 Oct 14 02:17:20 volumio volumio[1142]: info: CorePlayQueue::moveQueueItem 41 --> 41 Oct 14 02:17:20 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:17:23 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:17:23 volumio volumio[1142]: { Error: connect ECONNREFUSED 162.125.80.5:443 Oct 14 02:17:23 volumio volumio[1142]: at Object._errnoException (util.js:1022:11) Oct 14 02:17:23 volumio volumio[1142]: at _exceptionWithHostPort (util.js:1044:20) Oct 14 02:17:23 volumio volumio[1142]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 14 02:17:23 volumio volumio[1142]: code: 'ECONNREFUSED', Oct 14 02:17:23 volumio volumio[1142]: errno: 'ECONNREFUSED', Oct 14 02:17:23 volumio volumio[1142]: syscall: 'connect', Oct 14 02:17:23 volumio volumio[1142]: address: '162.125.80.5', Oct 14 02:17:23 volumio volumio[1142]: port: 443 } Oct 14 02:17:23 volumio volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:17:23 volumio sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-14 02:16 Oct 14 02:17:23 volumio sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"