-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-03-06 09:14:37 CST. -- Mar 06 09:13:20 smsl-n100 ntpd[2567]: 84.16.73.33 local addr 192.168.2.243 -> Mar 06 09:13:52 smsl-n100 volumio[4220]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 06 09:13:52 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 06 09:13:52 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 06 09:13:52 smsl-n100 sudo[5011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 06 09:13:52 smsl-n100 sudo[5011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:52 smsl-n100 sudo[5011]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 sudo[5018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 06 09:13:53 smsl-n100 sudo[5018]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5018]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 sudo[5025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 06 09:13:53 smsl-n100 sudo[5025]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5025]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 sudo[5031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 06 09:13:53 smsl-n100 sudo[5031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5031]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 sudo[5037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 09:13:53 smsl-n100 sudo[5037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5037]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 06 09:13:53 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 06 09:13:53 smsl-n100 sudo[5040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 09:13:53 smsl-n100 sudo[5040]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5040]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:53 smsl-n100 sudo[5043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 06 09:13:53 smsl-n100 sudo[5043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 09:13:53 smsl-n100 sudo[5043]: pam_unix(sudo:session): session closed for user root Mar 06 09:13:55 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioGetState Mar 06 09:13:57 smsl-n100 volumio[4220]: info: Executing endpoint metavolumio Mar 06 09:13:57 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 06 09:13:58 smsl-n100 volumio[4220]: info: Executing endpoint metavolumio Mar 06 09:13:58 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 06 09:14:02 smsl-n100 volumio[4220]: info: Executing endpoint metavolumio Mar 06 09:14:02 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 06 09:14:02 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:03 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:04 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:04 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:06 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:06 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:09 smsl-n100 volumio[4220]: error: Failed request for metavolumio API Mar 06 09:14:10 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:10 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:11 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:11 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:23 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:23 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:24 smsl-n100 ntpd[2567]: 5.79.108.34 local addr 192.168.2.243 -> Mar 06 09:14:27 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:27 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:32 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:14:32 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:32 smsl-n100 volumio[4220]: info: Preloading song: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67918 Mar 06 09:14:32 smsl-n100 volumio[4220]: info: Preloading song: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67919 Mar 06 09:14:32 smsl-n100 volumio[4220]: info: Exploding uri upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67918 in service upnp_browser Mar 06 09:14:32 smsl-n100 volumio[4220]: info: Exploding uri upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67919 in service upnp_browser Mar 06 09:14:34 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::ClearQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::stop Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::stPlaybackTimer Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::updateTrackBlock Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrackBlock Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:34 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:34 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::serviceStop Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::serviceStop Mar 06 09:14:34 smsl-n100 volumio[4220]: info: [1741223674929] ControllerUPNPBrowser::stop Mar 06 09:14:34 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand stop Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::clearPlayQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::saveQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::addQueueItems Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::addQueueItems Mar 06 09:14:34 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:34 smsl-n100 volumio[4220]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67918 Mar 06 09:14:34 smsl-n100 volumio[4220]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67918 Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::saveQueue Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::updateTrackBlock Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrackBlock Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPlay Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::play index 0 Mar 06 09:14:34 smsl-n100 volumio[4220]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::addQueueItems Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::addQueueItems Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Preload queue cleared Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67919 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_1644eb8e_61bed53f-67919 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::saveQueue Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::play index undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::updateTrackBlock Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrackBlock Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::startPlaybackTimer Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: [1741223675033] ControllerUPNPBrowser::clearAddPlayTrack Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand stop took 145 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand stop took 42 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand clear Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 26 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand clear took 24 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 20 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 16 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg" Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: 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} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: No code Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: 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} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: No code Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 228ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 226ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: error: updateQueue error: null Mar 06 09:14:35 smsl-n100 volumio[4220]: error: updateQueue error: null Mar 06 09:14:35 smsl-n100 volumio[4220]: error: updateQueue error: null Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 233ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 223 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg" Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 18ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 15ms Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 271ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Updating RAAT Signal Path Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: error: Serial API: Failed to decode command: 100, message: undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 06 09:14:35 smsl-n100 volumio[4220]: error: Serial API: Failed to decode command: 100, message: undefined Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand add "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg" took 119 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 25ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand play took 20 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 21ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 15ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 59 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 51 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 44 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 63 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 77ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 56 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 53 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 52 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 43 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 36 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus stop Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 281ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 350ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 343ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces system playlist update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Ignoring MPD Status Update Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Mar 06 09:14:35 smsl-n100 volumio[4220]: ---------------------------- MPD announces state update: player Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::getState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 259 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 258 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 251 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand playlistinfo took 250 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 57ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 29 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ------------------------------ 26ms Mar 06 09:14:35 smsl-n100 volumio[4220]: info: sendMpdCommand status took 21 milliseconds Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::parseState Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:35 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:35 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:35 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:36 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:36 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:36 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ControllerMpd::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::servicePushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CorePlayQueue::getTrack 0 Mar 06 09:14:36 smsl-n100 volumio[4220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":421,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"One World","artist":"鍾氏兄弟","album":"極EDGE (SACD)","uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfMTY0NGViOGVfNjFiZWQ1M2YtNjc5MTg","trackType":""} Mar 06 09:14:36 smsl-n100 volumio[4220]: verbose: CURRENT POSITION 0 Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState stateService play Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:14:36 smsl-n100 volumio[4220]: info: Received an update from plugin. extracting info from payload Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ------------------------------ 742ms Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ------------------------------ 741ms Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ------------------------------ 701ms Mar 06 09:14:36 smsl-n100 volumio[4220]: info: ------------------------------ 696ms Mar 06 09:14:36 smsl-n100 volumio[4220]: info: VolumeController::SetAlsaVolume0 Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreStateMachine::pushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:14:36 smsl-n100 volumio[4220]: info: CoreCommandRouter::volumioPushState Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:14:36 smsl-n100 volumio[4220]: info: MRS: Pushing multiroomSync output Mar 06 09:14:36 smsl-n100 volumio[4220]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 09:14:36 smsl-n100 volumio[4220]: Error: connect ETIMEDOUT 157.240.12.5:443 Mar 06 09:14:36 smsl-n100 volumio[4220]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 06 09:14:36 smsl-n100 volumio[4220]: errno: -110, Mar 06 09:14:36 smsl-n100 volumio[4220]: code: 'ETIMEDOUT', Mar 06 09:14:36 smsl-n100 volumio[4220]: syscall: 'connect', Mar 06 09:14:36 smsl-n100 volumio[4220]: address: '157.240.12.5', Mar 06 09:14:36 smsl-n100 volumio[4220]: port: 443 Mar 06 09:14:36 smsl-n100 volumio[4220]: } Mar 06 09:14:36 smsl-n100 volumio[4220]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 09:14:37 smsl-n100 sudo[5168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-06 09:13 Mar 06 09:14:37 smsl-n100 sudo[5168]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"