-- Logs begin at Thu 2019-02-14 18:12:04 CST, end at Sat 2024-10-26 08:38:23 CST. -- Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::servicePause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: [1729903020285] ControllerUPNPBrowser::pause Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand pause took 2 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: info: Oct 26 08:37:00 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:00 smsl-d400n volumio[1082]: info: Oct 26 08:37:00 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:00 smsl-d400n volumio[1082]: info: Oct 26 08:37:00 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand status took 8 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand status took 6 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand status took 4 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15832,"duration":515,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"773 Kbps","isStreaming":false,"title":"Johann Strauss - Frlingsstimmenwalzer op. 410","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/813.flac","trackType":"flac"} Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15832,"duration":515,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"773 Kbps","isStreaming":false,"title":"Johann Strauss - Frlingsstimmenwalzer op. 410","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/813.flac","trackType":"flac"} Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15832,"duration":515,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"773 Kbps","isStreaming":false,"title":"Johann Strauss - Frlingsstimmenwalzer op. 410","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/813.flac","trackType":"flac"} Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 5 Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:00 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ------------------------------ 74ms Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ------------------------------ 72ms Oct 26 08:37:00 smsl-d400n volumio[1082]: info: ------------------------------ 70ms Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:00 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:00 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::ClearQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::serviceStop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::serviceStop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: [1729903021668] ControllerUPNPBrowser::stop Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::clearPlayQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPlay Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::play index 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::play index undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: [1729903021719] ControllerUPNPBrowser::clearAddPlayTrack Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 68 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 19 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 12 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand clear took 10 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 8 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 6 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.95:8200/MediaItems/815.flac" Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: 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 26 08:37:01 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: 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 26 08:37:01 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 91ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 90ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 99ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 94 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.95:8200/MediaItems/815.flac" Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 13ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 12ms Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:01 smsl-d400n volumio[1082]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 116ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::ClearQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::clearPlayQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPlay Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::play index 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::play index undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: [1729903021923] ControllerUPNPBrowser::clearAddPlayTrack Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand add "http://192.168.2.95:8200/MediaItems/815.flac" took 79 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 5 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand play Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 25ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand play took 21 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand clear took 20 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 13ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 11ms Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.95:8200/MediaItems/815.flac" Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:01 smsl-d400n volumio[1082]: info: Oct 26 08:37:01 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 23ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 18 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.95:8200/MediaItems/815.flac" Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 13ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 11 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ------------------------------ 10ms Oct 26 08:37:01 smsl-d400n volumio[1082]: info: sendMpdCommand status took 8 milliseconds Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: 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 26 08:37:01 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:01 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: 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 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: 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 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 136ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 127ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 125ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand add "http://192.168.2.95:8200/MediaItems/815.flac" took 122 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 133ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand play took 131 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 130ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 126ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 20 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 16 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 14 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Oct 26 08:37:02 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 34ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 32 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 19ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 17 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 17ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand status took 13 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"851 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"851 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"851 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 112ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 134ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 132ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 79 milliseconds Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"851 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"699 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":527,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"699 Kbps","isStreaming":false,"title":"Johann Strauss - Die Fledermaus Ouverture","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/815.flac","trackType":"flac"} Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 7 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:02 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:02 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 237ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 221ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: ------------------------------ 218ms Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:02 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:02 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::ClearQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::serviceStop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::serviceStop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: [1729903023342] ControllerUPNPBrowser::stop Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::clearPlayQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPlay Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::play index 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::play index undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: [1729903023388] ControllerUPNPBrowser::clearAddPlayTrack Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 58 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 13 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 10 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand clear took 8 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 7 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 5 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.95:8200/MediaItems/816.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: 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 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: 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 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 87ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 86ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 93ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.95:8200/MediaItems/816.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 10ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 8ms Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 107ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand add "http://192.168.2.95:8200/MediaItems/816.flac" took 18 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 38ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand play took 36 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 16ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 15ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::ClearQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::clearPlayQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$0 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$1 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$2 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$3 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$4 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$5 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$6 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$7 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPlay Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::play index 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::addQueueItems Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Preload queue cleared Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$A Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Adding Item to queue: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Using cached record of: upnp/http://192.168.2.95:8200/ctl/ContentDir@1$7$31$B Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::play index undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: [1729903023634] ControllerUPNPBrowser::clearAddPlayTrack Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 82 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 87ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 81 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 78ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 76 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 74ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 71 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 12 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 28 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand clear took 27 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 23 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 21 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.95:8200/MediaItems/816.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Johann Strauss - Unter Donner und Blitz, Walzer op. 324","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/816.flac","trackType":"flac"} Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Johann Strauss - Unter Donner und Blitz, Walzer op. 324","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/816.flac","trackType":"flac"} Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Johann Strauss - Unter Donner und Blitz, Walzer op. 324","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/816.flac","trackType":"flac"} Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Johann Strauss - Unter Donner und Blitz, Walzer op. 324","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/816.flac","trackType":"flac"} Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: 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 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 8 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::play index undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: 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 26 08:37:03 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: No code Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 213ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 255ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 246ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 242ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: [1729903023818] ControllerUPNPBrowser::clearAddPlayTrack Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 166ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 163ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:03 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 169ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 163 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.95:8200/MediaItems/816.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 26 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 9ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 8ms Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 08:37:03 smsl-d400n volumio[1082]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 206ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand add "http://192.168.2.95:8200/MediaItems/816.flac" took 68 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand clear took 66 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand play Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.95:8200/MediaItems/817.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 18ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 18ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand play took 15 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.95:8200/MediaItems/817.flac" Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 38ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 37ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand add "http://192.168.2.95:8200/MediaItems/817.flac" took 8 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 6ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand play Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 10ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand play took 7 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 7ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ------------------------------ 5ms Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:03 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 12 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: info: sendMpdCommand status took 8 milliseconds Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:03 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:03 smsl-d400n volumio[1082]: info: Oct 26 08:37:03 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 27 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 23 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 12 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 9 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 79ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 92ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Oct 26 08:37:04 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 75ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 70 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 14ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 12 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 12ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand status took 9 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"821 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"874 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 244ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 242ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 234ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 226ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 230 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 230 milliseconds Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"874 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"778 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"778 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:04 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:04 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 399ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 337ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: ------------------------------ 334ms Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:04 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:04 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:05 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:05 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::servicePause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: [1729903026874] ControllerUPNPBrowser::pause Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: Oct 26 08:37:06 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand pause took 3 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:06 smsl-d400n volumio[1082]: info: Oct 26 08:37:06 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:06 smsl-d400n volumio[1082]: info: Oct 26 08:37:06 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand status took 6 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand status took 5 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand status took 2 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4209,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4209,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4209,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:06 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ------------------------------ 64ms Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ------------------------------ 62ms Oct 26 08:37:06 smsl-d400n volumio[1082]: info: ------------------------------ 61ms Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:06 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:07 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:07 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:07 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:08 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:08 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:09 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:09 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:10 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:10 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:11 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:11 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:12 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:12 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:13 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:13 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:14 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:14 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:15 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:15 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:17 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:17 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:18 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:18 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:19 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:19 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:19 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:19 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:20 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:20 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:21 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:21 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:21 smsl-d400n volumiologrotate[702]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 26 08:37:21 smsl-d400n volumiologrotate[702]: ls: cannot access 'D400N': No such file or directory Oct 26 08:37:23 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:23 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:24 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:24 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:24 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: Oct 26 08:37:25 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:25 smsl-d400n volumio[1082]: info: Oct 26 08:37:25 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:25 smsl-d400n volumio[1082]: info: Oct 26 08:37:25 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand status took 7 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand status took 4 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand status took 3 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: 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":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: 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":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: 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":"Johann Strauss (Vater) - Radetzky-Marsch op. 228","artist":"卡拉扬","album":"1987年维也纳新年音乐会","uri":"http://192.168.2.95:8200/MediaItems/817.flac","trackType":"flac"} Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus pause Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:25 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ------------------------------ 78ms Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ------------------------------ 76ms Oct 26 08:37:25 smsl-d400n volumio[1082]: info: ------------------------------ 75ms Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:25 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:25 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Clearing queue after UPNP request Oct 26 08:37:26 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::ClearQueue Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::stop Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::updateTrackBlock Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrackBlock Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::stPlaybackTimer Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:26 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::serviceStop Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::serviceStop Oct 26 08:37:26 smsl-d400n volumio[1082]: info: [1729903046382] ControllerUPNPBrowser::stop Oct 26 08:37:26 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::clearPlayQueue Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::saveQueue Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:26 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushQueue Oct 26 08:37:26 smsl-d400n volumio[1082]: info: sendMpdCommand stop took 38 milliseconds Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:26 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:26 smsl-d400n volumio[1082]: error: updateQueue error: null Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 9ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 8ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 7ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Starting UPNP Playback Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Preparing playback through UPNP Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CorePlayQueue::getTrack 9 Oct 26 08:37:26 smsl-d400n volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 6ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Oct 26 08:37:26 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:26 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 6ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 5ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 5ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 4ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 9ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 3ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 11 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 8 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 7 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 22 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 10 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Korg S - Wait Time","artist":"Korg S","album":null,"uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Korg S - Wait Time","artist":"Korg S","album":null,"uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Korg S - Wait Time","artist":"Korg S","album":null,"uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 92ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 119ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 118ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 104 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 96 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 10ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 7 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 185ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 171ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces system playlist update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Ignoring MPD Status Update Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Oct 26 08:37:27 smsl-d400n volumio[1082]: ---------------------------- MPD announces state update: player Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::getState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 106ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 105 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 97 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 41ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand status took 39 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 247ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 149ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: ControllerMpd::parseTrackInfo Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ControllerMpd::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::servicePushState Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: In UPNP mode Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wait Time","artist":"Korg S","album":"Wait Time","uri":"http://fsandroid.tx.kugou.com/202410260837/f80c6264d8cd2aa89c1720ef3b69b7b6/v3/64435816ea6efd2feee14d9f2c6792c3/yp/full/ap1005_us929147236_df2lydbw3x3vr01ryy3p0inf7r_pi2_mx268141658_qu320_s1934419318.mp3","trackType":"mp3"} Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: CURRENT POSITION 9 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState stateService play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::syncState currentStatus play Oct 26 08:37:27 smsl-d400n volumio[1082]: info: Received an update from plugin. extracting info from payload Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::pushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 26 08:37:27 smsl-d400n volumio[1082]: info: MRS: Pushing multiroomSync output Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 180ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: ------------------------------ 116ms Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreStateMachine::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CorePlayQueue::getQueue Oct 26 08:37:27 smsl-d400n volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:37:27 smsl-d400n volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 26 08:37:28 smsl-d400n volumio[1082]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 08:38:22 smsl-d400n volumiologrotate[702]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 26 08:38:22 smsl-d400n volumiologrotate[702]: ls: cannot access 'D400N': No such file or directory Oct 26 08:38:22 smsl-d400n volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:38:22 smsl-d400n volumio[1082]: Error: connect ETIMEDOUT 157.240.3.50:443 Oct 26 08:38:22 smsl-d400n volumio[1082]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 26 08:38:22 smsl-d400n volumio[1082]: errno: -110, Oct 26 08:38:22 smsl-d400n volumio[1082]: code: 'ETIMEDOUT', Oct 26 08:38:22 smsl-d400n volumio[1082]: syscall: 'connect', Oct 26 08:38:22 smsl-d400n volumio[1082]: address: '157.240.3.50', Oct 26 08:38:22 smsl-d400n volumio[1082]: port: 443 Oct 26 08:38:22 smsl-d400n volumio[1082]: } Oct 26 08:38:22 smsl-d400n volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:38:23 smsl-d400n sudo[2515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 08:37 Oct 26 08:38:23 smsl-d400n sudo[2515]: 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="e71179fefb92227f783fc914e012504f08dcf6fb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Oct 2024 09:45:35 AM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="c83b50df238823988635e61d61230ef1"