-- Logs begin at Sun 2025-01-19 15:00:48 UTC, end at Sun 2025-01-19 15:06:43 UTC. -- Jan 19 15:05:00 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:00 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:04 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:04 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:07 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:11 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:12 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:14 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:16 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:17 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:20 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:21 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:24 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:24 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:28 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:28 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:31 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:32 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:35 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:36 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:37 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 19 15:05:37 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand rescan Jan 19 15:05:37 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:37 volumio volumio[698]: info: Jan 19 15:05:37 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:37 volumio volumio[698]: info: sendMpdCommand rescan took 9 milliseconds Jan 19 15:05:37 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:37 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:38 volumio volumio[698]: info: Jan 19 15:05:38 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:38 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:38 volumio volumio[698]: info: sendMpdCommand status took 11 milliseconds Jan 19 15:05:38 volumio volumio[698]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:38 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:38 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: 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} Jan 19 15:05:38 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: No code Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: ------------------------------ 114ms Jan 19 15:05:38 volumio volumio[698]: info: sendMpdCommand status took 110 milliseconds Jan 19 15:05:38 volumio volumio[698]: info: sendMpdCommand status took 107 milliseconds Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:38 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:38 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:38 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: 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} Jan 19 15:05:38 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: No code Jan 19 15:05:38 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:38 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:38 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:38 volumio volumio[698]: info: ------------------------------ 180ms Jan 19 15:05:38 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:40 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:41 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand rescan Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:44 volumio volumio[698]: info: Jan 19 15:05:44 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:44 volumio volumio[698]: info: sendMpdCommand rescan took 8 milliseconds Jan 19 15:05:44 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:44 volumio volumio[698]: info: Jan 19 15:05:44 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:44 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:44 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:05:44 volumio volumio[698]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:44 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:44 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: 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} Jan 19 15:05:44 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: No code Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: ------------------------------ 113ms Jan 19 15:05:44 volumio volumio[698]: info: sendMpdCommand status took 110 milliseconds Jan 19 15:05:44 volumio volumio[698]: info: sendMpdCommand status took 108 milliseconds Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:44 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:44 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:44 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: 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} Jan 19 15:05:44 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: No code Jan 19 15:05:44 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:44 volumio volumio[698]: info: ------------------------------ 188ms Jan 19 15:05:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:45 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: Jan 19 15:05:48 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand update took 9 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: Jan 19 15:05:48 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: 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} Jan 19 15:05:48 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: No code Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: ------------------------------ 78ms Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 75 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 74 milliseconds Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: 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} Jan 19 15:05:48 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: No code Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: ------------------------------ 154ms Jan 19 15:05:48 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: Jan 19 15:05:48 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: Jan 19 15:05:48 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:48 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: 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} Jan 19 15:05:48 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: No code Jan 19 15:05:48 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:48 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:48 volumio volumio[698]: info: ------------------------------ 78ms Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 75 milliseconds Jan 19 15:05:48 volumio volumio[698]: info: sendMpdCommand status took 72 milliseconds Jan 19 15:05:48 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:49 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:49 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:49 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:49 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:49 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:49 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:49 volumio volumio[698]: 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} Jan 19 15:05:49 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:49 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:49 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:49 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:49 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:49 volumio volumio[698]: info: No code Jan 19 15:05:49 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:49 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:49 volumio volumio[698]: info: ------------------------------ 142ms Jan 19 15:05:49 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: Jan 19 15:05:50 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand update took 8 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: Jan 19 15:05:50 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 6 milliseconds Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: 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} Jan 19 15:05:50 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: No code Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: ------------------------------ 77ms Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 74 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 72 milliseconds Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: 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} Jan 19 15:05:50 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: No code Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: ------------------------------ 148ms Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: Jan 19 15:05:50 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand update took 8 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: Jan 19 15:05:50 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 6 milliseconds Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: 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} Jan 19 15:05:50 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: No code Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: ------------------------------ 75ms Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 72 milliseconds Jan 19 15:05:50 volumio volumio[698]: info: sendMpdCommand status took 70 milliseconds Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:05:50 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:05:50 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: 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} Jan 19 15:05:50 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: No code Jan 19 15:05:50 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:05:50 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:05:50 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:05:50 volumio volumio[698]: info: ------------------------------ 146ms Jan 19 15:05:52 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:52 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:05:55 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:05:55 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 15:05:56 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 15:05:58 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 15:05:59 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:02 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:02 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jan 19 15:06:05 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:09 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:12 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:13 volumio volumio[698]: info: CURURI: playlists Jan 19 15:06:13 volumio volumio[698]: info: Listing playlists Jan 19 15:06:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:14 volumio volumio[698]: info: CURURI: music-library Jan 19 15:06:15 volumio volumio[1051]: [Vollibrespot] : error sending packet Os { code: 1, kind: PermissionDenied, message: "Operation not permitted" } Jan 19 15:06:16 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:16 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:16 volumio volumio[698]: info: CURURI: music-library/USB Jan 19 15:06:16 volumio volumio[698]: error: Failed LSINFO: null Jan 19 15:06:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:18 volumio volumio[698]: info: CURURI: music-library Jan 19 15:06:19 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:06:21 volumio volumio[698]: info: Jan 19 15:06:21 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:06:21 volumio volumio[698]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:06:21 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:06:21 volumio volumio[698]: info: Jan 19 15:06:21 volumio volumio[698]: ---------------------------- MPD announces state update: update Jan 19 15:06:21 volumio volumio[698]: info: ControllerMpd::getState Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:06:21 volumio volumio[698]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:06:21 volumio volumio[698]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:06:21 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:06:21 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: 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} Jan 19 15:06:21 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: No code Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: ------------------------------ 106ms Jan 19 15:06:21 volumio volumio[698]: info: sendMpdCommand status took 101 milliseconds Jan 19 15:06:21 volumio volumio[698]: info: sendMpdCommand status took 99 milliseconds Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:06:21 volumio volumio[698]: info: Command Router : Notfying DB Updatefalse Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:06:21 volumio volumio[698]: verbose: ControllerMpd::parseState Jan 19 15:06:21 volumio volumio[698]: info: ControllerMpd::pushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::servicePushState Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: 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} Jan 19 15:06:21 volumio volumio[698]: verbose: CURRENT POSITION 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::syncState stateService stop Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: No code Jan 19 15:06:21 volumio volumio[698]: info: CoreStateMachine::pushState Jan 19 15:06:21 volumio volumio[698]: info: CorePlayQueue::getTrack 0 Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:21 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:21 volumio volumio[698]: info: ------------------------------ 167ms Jan 19 15:06:22 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:26 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:28 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:28 volumio volumio[698]: info: CURURI: artists:// Jan 19 15:06:29 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:30 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:30 volumio volumio[698]: info: CURURI: music-library Jan 19 15:06:31 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 15:06:31 volumio volumio[698]: info: CURURI: playlists Jan 19 15:06:31 volumio volumio[698]: info: Listing playlists Jan 19 15:06:33 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:33 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 19 15:06:33 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 19 15:06:35 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 15:06:36 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 19 15:06:36 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:40 volumio kernel: hub 2-0:1.0: Cannot enable port 1. Maybe the USB cable is bad? Jan 19 15:06:41 volumio volumio[698]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 15:06:41 volumio volumio[698]: info: CoreStateMachine::ClearQueue Jan 19 15:06:41 volumio volumio[698]: info: CoreStateMachine::stop Jan 19 15:06:41 volumio volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 15:06:41 volumio volumio[698]: verbose: UNSET VOLATILE Jan 19 15:06:41 volumio volumio[698]: info: CorePlayQueue::clearPlayQueue Jan 19 15:06:41 volumio volumio[698]: info: CorePlayQueue::saveQueue Jan 19 15:06:41 volumio volumio[698]: info: CoreCommandRouter::volumioPushState Jan 19 15:06:41 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:06:41 volumio volumio[698]: info: CoreCommandRouter::volumioPushQueue Jan 19 15:06:41 volumio volumio[698]: info: CoreStateMachine::addQueueItems Jan 19 15:06:41 volumio volumio[698]: info: CorePlayQueue::addQueueItems Jan 19 15:06:41 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track09Thanh Pho Tinh Yeu Va Noi Nho.wav Jan 19 15:06:41 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track09Thanh Pho Tinh Yeu Va Noi Nho.wav in service mpd Jan 19 15:06:41 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track09Thanh Pho Tinh Yeu Va Noi Nho.wav' Jan 19 15:06:41 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track13Ru Bien.wav Jan 19 15:06:41 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track13Ru Bien.wav in service mpd Jan 19 15:06:41 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track13Ru Bien.wav' Jan 19 15:06:41 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track12Tinh Bien.wav Jan 19 15:06:41 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track12Tinh Bien.wav in service mpd Jan 19 15:06:41 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track12Tinh Bien.wav' Jan 19 15:06:41 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track11Oi Con Song Dinh.wav Jan 19 15:06:41 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track11Oi Con Song Dinh.wav in service mpd Jan 19 15:06:41 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track11Oi Con Song Dinh.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track10Thuong Em Chin Doi Muoi Mong.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track10Thuong Em Chin Doi Muoi Mong.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track10Thuong Em Chin Doi Muoi Mong.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track08Em Yeu Anh Nhu Yeu Cau Vi Dam.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track08Em Yeu Anh Nhu Yeu Cau Vi Dam.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track08Em Yeu Anh Nhu Yeu Cau Vi Dam.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track07Cho Anh Xin So Nha.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track07Cho Anh Xin So Nha.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track07Cho Anh Xin So Nha.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track06Ngay Tro Ve.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track06Ngay Tro Ve.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track06Ngay Tro Ve.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track05Em Van Doi Anh Ve.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track05Em Van Doi Anh Ve.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track05Em Van Doi Anh Ve.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track04Duong Lang Nam Ay.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track04Duong Lang Nam Ay.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track04Duong Lang Nam Ay.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track03Ngay Mai Anh Len Duong.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track03Ngay Mai Anh Len Duong.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track03Ngay Mai Anh Len Duong.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track02Mua Xuan Ben Cua So.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track02Mua Xuan Ben Cua So.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track02Mua Xuan Ben Cua So.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track01Bai Ca Tam Biet.wav Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track01Bai Ca Tam Biet.wav in service mpd Jan 19 15:06:42 volumio volumio[698]: scanFolder - failure to stat '/mnt/USB/MUSIC/Long Nhat - Mua Xuan Ben Cua So/Track01Bai Ca Tam Biet.wav' Jan 19 15:06:42 volumio volumio[698]: info: Adding Item to queue: cue://USB/MUSIC/Nhac Xuan Chon Loc 2020 [16CD+104 bai hat] [FLAC + WAV]/Ruoc Xuan Ve Nha (2006) [FLAC]/Ruoc Xuan Ve Nha.cue@0 Jan 19 15:06:42 volumio volumio[698]: info: Exploding uri cue://USB/MUSIC/Nhac Xuan Chon Loc 2020 [16CD+104 bai hat] [FLAC + WAV]/Ruoc Xuan Ve Nha (2006) [FLAC]/Ruoc Xuan Ve Nha.cue@0 in service mpd Jan 19 15:06:42 volumio volumio[698]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:06:42 volumio volumio[698]: Error: file /mnt/USB/MUSIC/Nhac Xuan Chon Loc 2020 [16CD+104 bai hat] [FLAC + WAV]/Ruoc Xuan Ve Nha (2006) [FLAC]/Ruoc Xuan Ve Nha.cue does not exist Jan 19 15:06:42 volumio volumio[698]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:37:15) Jan 19 15:06:42 volumio volumio[698]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1876:27) Jan 19 15:06:42 volumio volumio[698]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1283:16) Jan 19 15:06:42 volumio volumio[698]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Jan 19 15:06:42 volumio volumio[698]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:238:25) Jan 19 15:06:42 volumio volumio[698]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:476:23) Jan 19 15:06:42 volumio volumio[698]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:452:17) Jan 19 15:06:42 volumio volumio[698]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:106:35) Jan 19 15:06:42 volumio volumio[698]: at emitTwo (events.js:126:13) Jan 19 15:06:42 volumio volumio[698]: at Socket.emit (events.js:214:7) Jan 19 15:06:42 volumio volumio[698]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 19 15:06:42 volumio volumio[698]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jan 19 15:06:42 volumio volumio[698]: at process._tickCallback (internal/process/next_tick.js:180:9) Jan 19 15:06:42 volumio volumio[698]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:06:43 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 15:05 Jan 19 15:06:43 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 12:12:00 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="sparky" VOLUMIO_HASH="1507c65028120e16c14994c066bc5dd4"