-- Logs begin at Thu 2024-10-31 15:37:19 UTC, end at Thu 2024-10-31 20:10:06 UTC. -- Oct 31 20:09:14 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioAddQueueItems Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::addQueueItems Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::addQueueItems Oct 31 20:09:14 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:14 volumio volumio[12816]: info: Adding Item to queue: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@8d72b1f1a4e90da28771 Oct 31 20:09:14 volumio volumio[12816]: info: Using cached record of: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@8d72b1f1a4e90da28771 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::saveQueue Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPlay Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::play index 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::stop Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::play index undefined Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::startPlaybackTimer Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: [1730405354880] ControllerUPNPBrowser::clearAddPlayTrack Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand stop took 12 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 4 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand clear took 3 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 3 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3" Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: 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 31 20:09:14 volumio volumio[12816]: verbose: CURRENT POSITION 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState stateService stop Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: No code Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: 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 31 20:09:14 volumio volumio[12816]: verbose: CURRENT POSITION 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState stateService stop Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: No code Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 28ms Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 28ms Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:14 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:14 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 28ms Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3" Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:14 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 34ms Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand add "http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3" took 10 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand play Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:14 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand play took 2 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 1ms Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: info: Oct 31 20:09:14 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand status took 0 milliseconds Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:14 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:14 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3","trackType":"mp3"} Oct 31 20:09:14 volumio volumio[12816]: verbose: CURRENT POSITION 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3","trackType":"mp3"} Oct 31 20:09:14 volumio volumio[12816]: verbose: CURRENT POSITION 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:14 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:14 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:14 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3","trackType":"mp3"} Oct 31 20:09:14 volumio volumio[12816]: verbose: CURRENT POSITION 574 Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:14 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:14 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 8ms Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:09:14 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:09:45 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioAddQueueItems Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::addQueueItems Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::addQueueItems Oct 31 20:09:45 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:45 volumio volumio[12816]: info: Adding Item to queue: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@82627f73dfaf81eb78db Oct 31 20:09:45 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@82627f73dfaf81eb78db in service upnp_browser Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::saveQueue Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPlay Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::play index 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::stop Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::serviceStop Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 574 Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::serviceStop Oct 31 20:09:45 volumio volumio[12816]: info: [1730405385283] ControllerUPNPBrowser::stop Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand stop took 12 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::play index undefined Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::startPlaybackTimer Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: info: [1730405385297] ControllerUPNPBrowser::clearAddPlayTrack Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 4 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand stop took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand clear took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3" Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: 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":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/8d72b1f1a4e90da28771/file.mp3","trackType":"mp3"} Oct 31 20:09:45 volumio volumio[12816]: verbose: CURRENT POSITION 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState stateService stop Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: No code Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:45 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 23ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 20ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 19ms Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 17ms Oct 31 20:09:45 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:45 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3" Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 17ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 17ms Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand add "http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3" took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand play Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:45 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 3ms Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand play took 3 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: Oct 31 20:09:45 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3","trackType":"mp3"} Oct 31 20:09:45 volumio volumio[12816]: verbose: CURRENT POSITION 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3","trackType":"mp3"} Oct 31 20:09:45 volumio volumio[12816]: verbose: CURRENT POSITION 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:45 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:45 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:45 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3","trackType":"mp3"} Oct 31 20:09:45 volumio volumio[12816]: verbose: CURRENT POSITION 575 Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:45 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:45 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 8ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 10ms Oct 31 20:09:45 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:09:54 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioAddQueueItems Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::addQueueItems Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::addQueueItems Oct 31 20:09:54 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:54 volumio volumio[12816]: info: Adding Item to queue: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@977c6f0fc7e937b5e431 Oct 31 20:09:54 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@977c6f0fc7e937b5e431 in service upnp_browser Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::saveQueue Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPlay Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::play index 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::stop Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::serviceStop Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 575 Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::serviceStop Oct 31 20:09:54 volumio volumio[12816]: info: [1730405394389] ControllerUPNPBrowser::stop Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand stop took 13 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::play index undefined Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::startPlaybackTimer Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: info: [1730405394404] ControllerUPNPBrowser::clearAddPlayTrack Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 4 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand stop took 3 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand clear took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3" Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: 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":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/82627f73dfaf81eb78db/file.mp3","trackType":"mp3"} Oct 31 20:09:54 volumio volumio[12816]: verbose: CURRENT POSITION 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState stateService stop Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: No code Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:54 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 20ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 17ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 16ms Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 15ms Oct 31 20:09:54 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:54 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3" Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 37ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 37ms Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand add "http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3" took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand play Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:54 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 4ms Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand play took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 1ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 2ms Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: Oct 31 20:09:54 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:54 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3","trackType":"mp3"} Oct 31 20:09:54 volumio volumio[12816]: verbose: CURRENT POSITION 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3","trackType":"mp3"} Oct 31 20:09:54 volumio volumio[12816]: verbose: CURRENT POSITION 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:54 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:54 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:54 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3","trackType":"mp3"} Oct 31 20:09:54 volumio volumio[12816]: verbose: CURRENT POSITION 576 Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:54 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:54 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 16ms Oct 31 20:09:54 volumio volumio[12816]: info: ------------------------------ 17ms Oct 31 20:09:56 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioAddQueueItems Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::addQueueItems Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::addQueueItems Oct 31 20:09:56 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:09:56 volumio volumio[12816]: info: Adding Item to queue: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f978a20544645e5f4ae2 Oct 31 20:09:56 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f978a20544645e5f4ae2 in service upnp_browser Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::saveQueue Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPlay Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::play index 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::stop Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::updateTrackBlock Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrackBlock Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::serviceStop Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 576 Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::serviceStop Oct 31 20:09:56 volumio volumio[12816]: info: [1730405396398] ControllerUPNPBrowser::stop Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand stop took 14 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::play index undefined Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::startPlaybackTimer Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: info: [1730405396414] ControllerUPNPBrowser::clearAddPlayTrack Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 4 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand stop took 3 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 2 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand clear took 2 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3" Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: 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":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/977c6f0fc7e937b5e431/file.mp3","trackType":"mp3"} Oct 31 20:09:56 volumio volumio[12816]: verbose: CURRENT POSITION 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState stateService stop Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: No code Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 28ms Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 26ms Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 27ms Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 32ms Oct 31 20:09:56 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:56 volumio volumio[12816]: error: updateQueue error: null Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3" Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 21ms Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 22ms Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand add "http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3" took 3 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand play Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces system playlist update Oct 31 20:09:56 volumio volumio[12816]: info: Ignoring MPD Status Update Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 4ms Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand play took 2 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 1ms Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 1ms Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: Oct 31 20:09:56 volumio volumio[12816]: ---------------------------- MPD announces state update: player Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::getState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand status Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand status took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseState Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: verbose: ControllerMpd::parseTrackInfo Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3","trackType":"mp3"} Oct 31 20:09:56 volumio volumio[12816]: verbose: CURRENT POSITION 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3","trackType":"mp3"} Oct 31 20:09:56 volumio volumio[12816]: verbose: CURRENT POSITION 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:56 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: ControllerMpd::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::servicePushState Oct 31 20:09:56 volumio volumio[12816]: info: CorePlayQueue::getTrack 577 Oct 31 20:09:56 volumio volumio[12816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.0.100:32469/object/f978a20544645e5f4ae2/file.mp3","trackType":"mp3"} Oct 31 20:09:56 volumio volumio[12816]: verbose: CURRENT POSITION 577 Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState stateService play Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:09:56 volumio volumio[12816]: info: Received an update from plugin. extracting info from payload Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreStateMachine::pushState Oct 31 20:09:56 volumio volumio[12816]: info: CoreCommandRouter::volumioPushState Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 7ms Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:09:56 volumio volumio[12816]: info: ------------------------------ 11ms Oct 31 20:10:01 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 20:10:03 volumio volumio[12816]: info: Preload queue cleared Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@11ce572d2ef0beeafdcb Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@768bbed2653020100ebc Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@9e564d7a6af6c1bfdfc9 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@6898df28512ecae648e9 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@645fb558283e33d13e39 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ae2c671df8154bf2fc57 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@9d86badc7c6e92d93f84 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@14700fadd76516f54fec Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@27d437b3f11adff61063 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@2892e3bb0fd694c0d880 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@42f119e1fa98b4839067 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f6c5df91dc8f1e7494b4 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e12f4ed49cd4b9153a4c Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@0b4db0f5cd1bbcc632aa Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@2c7987f4db9e45f3ea64 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@14df7257c4901bf4129e Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e6e3146cae081c1cfeca Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a4491c9a1709603ef5b7 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@596d6f8b7049452f045b Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bebc01c0dc6fc8dd68e3 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@7e05018151a6357abc3d Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@67b2559f3a7f739fe05f Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f1e7569176c02e741618 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e6422ebc6e92f8ddc9aa Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e4dbb0b99349ddf05423 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ce8e0974518d43e6a409 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@513ce558563928b2f573 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@b6edf2159d294df19084 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@fcd63f6dfe6d613b1684 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f810684b0dd601fc5070 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@6582a9ba3201d6406d2b Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@73cd0b2b37d227855fa5 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@eb74f01b750ed805b4ce Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@75a06d24ee653ccada67 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@8952a5c3a949a428cf03 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a82d1fe84db34d8b8ee4 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@4e3070130a59af83f32d Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@4f718a0cba38bb5b9e10 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c708c8ec61ec2f774b83 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bbdaabbaf7c61180706f Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@62c29ef0eb03b6356ad5 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@303a3c1ba34a6d455a88 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@d6710195a9b5e3710b94 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ac9923443f24e22b4206 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@353e74ff019a5d675752 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ad0c2fbb75c52f511eb2 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@69dd2f07c5ab46b6ce43 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@7bfd1de17ab1d5ea803c Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@d5f39390289b2c0d40d5 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c317542c0deb4cd8ae2f Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ed0d8a754ac490bccd51 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@02e2b9c0e4212149126b Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a155a36f781d355c16c7 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@91113c804f17997d0685 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@b4337aa62cd4c5706a2f Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ae5c8bafad5560f784dd Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@fe96fce019ea6cc030df Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@14b901df2f7f0d84ad76 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@96987beebecd06a31221 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@0f90beab2410de2948fa Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ac08aac8b1656a82f380 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@77e4a6604186992114c4 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a26d8545873e4d20f168 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@01e910f386fd686badc0 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f81ef0d73649318811a6 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a024e11f0f1416a09993 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c61aaa646f8b926fb63c Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@2c906811c5d258bb749a Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@8d0016e8045392a687fa Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a3cc7a5306f7bea940fc Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@db93759346e5b8ddbb3d Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@6e5ce71f4622f626c44b Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@571ac7735a1f0210c73e Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@30cef33a6276e7baf79a Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e32d147ff1bc86f03567 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@d2a69e97f956e71a11c3 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f520af5e21f5200abde7 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bc1cc3cdb6ef4e7c1f39 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@439e9b2c0a844d42917b Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a4b89bdaf543fc5fe9f2 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@1449398cc6a91310a755 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f135ce704f61e3fca7e3 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@add977e1482b39fa6d75 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c1796b6dc4bcd4bf2489 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f7efe697472d917741f4 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@dd112508469a1bae0cf4 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ce5cb7797247d979c076 Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bde785c1ea737005f3ee Oct 31 20:10:03 volumio volumio[12816]: info: Preloading song: upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@3181a23ae04634163218 Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@11ce572d2ef0beeafdcb in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@768bbed2653020100ebc in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@9e564d7a6af6c1bfdfc9 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@6898df28512ecae648e9 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@645fb558283e33d13e39 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ae2c671df8154bf2fc57 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@9d86badc7c6e92d93f84 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@14700fadd76516f54fec in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@27d437b3f11adff61063 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@2892e3bb0fd694c0d880 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@42f119e1fa98b4839067 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f6c5df91dc8f1e7494b4 in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e12f4ed49cd4b9153a4c in service upnp_browser Oct 31 20:10:03 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@0b4db0f5cd1bbcc632aa in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@2c7987f4db9e45f3ea64 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@14df7257c4901bf4129e in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e6e3146cae081c1cfeca in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a4491c9a1709603ef5b7 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@596d6f8b7049452f045b in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bebc01c0dc6fc8dd68e3 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@7e05018151a6357abc3d in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@67b2559f3a7f739fe05f in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f1e7569176c02e741618 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e6422ebc6e92f8ddc9aa in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@e4dbb0b99349ddf05423 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ce8e0974518d43e6a409 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@513ce558563928b2f573 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@b6edf2159d294df19084 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@fcd63f6dfe6d613b1684 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@f810684b0dd601fc5070 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@6582a9ba3201d6406d2b in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@73cd0b2b37d227855fa5 in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@eb74f01b750ed805b4ce in service upnp_browser Oct 31 20:10:04 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@75a06d24ee653ccada67 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@8952a5c3a949a428cf03 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a82d1fe84db34d8b8ee4 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@4e3070130a59af83f32d in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@4f718a0cba38bb5b9e10 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c708c8ec61ec2f774b83 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@bbdaabbaf7c61180706f in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@62c29ef0eb03b6356ad5 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@303a3c1ba34a6d455a88 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@d6710195a9b5e3710b94 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ac9923443f24e22b4206 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@353e74ff019a5d675752 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ad0c2fbb75c52f511eb2 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@69dd2f07c5ab46b6ce43 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@7bfd1de17ab1d5ea803c in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@d5f39390289b2c0d40d5 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@c317542c0deb4cd8ae2f in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ed0d8a754ac490bccd51 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@02e2b9c0e4212149126b in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@a155a36f781d355c16c7 in service upnp_browser Oct 31 20:10:05 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@91113c804f17997d0685 in service upnp_browser Oct 31 20:10:06 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@b4337aa62cd4c5706a2f in service upnp_browser Oct 31 20:10:06 volumio volumio[12816]: info: Exploding uri upnp/http://192.168.0.100:32469/ContentDirectory/5c0fe2e4-8be4-f680-8af4-6f6c2c40eb20/control.xml@ae5c8bafad5560f784dd in service upnp_browser Oct 31 20:10:06 volumio volumio[12816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 20:10:06 volumio volumio[12816]: TypeError: Cannot read property 'length' of undefined Oct 31 20:10:06 volumio volumio[12816]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 31 20:10:06 volumio volumio[12816]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 31 20:10:06 volumio volumio[12816]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 31 20:10:06 volumio volumio[12816]: at Parser.emit (events.js:400:28) Oct 31 20:10:06 volumio volumio[12816]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 31 20:10:06 volumio volumio[12816]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 31 20:10:06 volumio volumio[12816]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 31 20:10:06 volumio volumio[12816]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 31 20:10:06 volumio volumio[12816]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 31 20:10:06 volumio volumio[12816]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 31 20:10:06 volumio volumio[12816]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 31 20:10:06 volumio volumio[12816]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 31 20:10:06 volumio volumio[12816]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 31 20:10:06 volumio volumio[12816]: at IncomingMessage.emit (events.js:412:35) Oct 31 20:10:06 volumio volumio[12816]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 31 20:10:06 volumio volumio[12816]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 31 20:10:06 volumio volumio[12816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 20:10:06 volumio sudo[14188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-31 20:09 Oct 31 20:10:06 volumio sudo[14188]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"