-- Logs begin at Thu 2019-02-14 18:12:04 CST, end at Tue 2024-07-09 09:49:36 CST. -- Jul 09 09:48:12 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:48:12 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:48:12 s100-1 volumio[1089]: info: Discovery: Getting this device information Jul 09 09:48:12 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:12 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:12 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:48:13 s100-1 volumio[1089]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9D%B0%E5%80%AB/%E8%8C%83%E7%89%B9%E8%A5%BF/fdfeba0d-e889-4ddc-890d-f2feae30a047.jpg' Jul 09 09:48:15 s100-1 sudo[2112]: volumio : unable to resolve host s100-1 Jul 09 09:48:15 s100-1 sudo[2114]: volumio : unable to resolve host s100-1 Jul 09 09:48:15 s100-1 sudo[2112]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 09 09:48:15 s100-1 sudo[2114]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 09 09:48:15 s100-1 sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 09:48:15 s100-1 sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 09:48:15 s100-1 sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:48:15 s100-1 sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:48:15 s100-1 sudo[2112]: pam_unix(sudo:session): session closed for user root Jul 09 09:48:15 s100-1 sudo[2114]: pam_unix(sudo:session): session closed for user root Jul 09 09:48:15 s100-1 volumio[1089]: verbose: New Socket.io Connection to 192.168.2.144 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:15 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 09:48:15 s100-1 volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 09 09:48:15 s100-1 volumio[1089]: info: Received Get System Info Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:48:15 s100-1 volumio[1089]: info: Discovery: Getting this device information Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:15 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:15 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:15 s100-1 volumio[1089]: info: Listing playlists Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 09:48:15 s100-1 volumio[1089]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9D%B0%E5%80%AB/%E8%8C%83%E7%89%B9%E8%A5%BF/fdfeba0d-e889-4ddc-890d-f2feae30a047.jpg' Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 09:48:15 s100-1 volumio[1089]: info: Received Get System Info Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:48:15 s100-1 volumio[1089]: info: Discovery: Getting this device information Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:15 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:48:15 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 09:48:17 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 09 09:48:25 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 09:48:28 s100-1 volumio[1089]: info: Clearing queue after UPNP request Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::ClearQueue Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::stPlaybackTimer Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::updateTrackBlock Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrackBlock Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::serviceStop Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::serviceStop Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::stop Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::clearPlayQueue Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::saveQueue Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushQueue Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:28 s100-1 volumio[1089]: info: sendMpdCommand stop took 28 milliseconds Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:28 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:28 s100-1 volumio[1089]: info: sendMpdCommand status took 9 milliseconds Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: 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} Jul 09 09:48:28 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: No code Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 38ms Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:28 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 38ms Jul 09 09:48:28 s100-1 volumio[1089]: info: sendMpdCommand status took 7 milliseconds Jul 09 09:48:28 s100-1 volumio[1089]: info: sendMpdCommand status took 5 milliseconds Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:28 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: 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} Jul 09 09:48:28 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: No code Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: 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} Jul 09 09:48:28 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: No code Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:28 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 52ms Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 51ms Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:28 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:28 s100-1 volumio[1089]: info: Jul 09 09:48:28 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:28 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:28 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:28 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 38ms Jul 09 09:48:28 s100-1 volumio[1089]: info: ------------------------------ 36ms Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:28 s100-1 volumio[1089]: info: Starting UPNP Playback Jul 09 09:48:28 s100-1 volumio[1089]: info: Preparing playback through UPNP Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:28 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:28 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 09:48:29 s100-1 volumio[1089]: info: Clearing queue after UPNP request Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreStateMachine::ClearQueue Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreStateMachine::stop Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 09:48:29 s100-1 volumio[1089]: info: CorePlayQueue::clearPlayQueue Jul 09 09:48:29 s100-1 volumio[1089]: info: CorePlayQueue::saveQueue Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:29 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:29 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushQueue Jul 09 09:48:29 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:29 s100-1 volumio[1089]: info: Jul 09 09:48:29 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:29 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:29 s100-1 volumio[1089]: info: Jul 09 09:48:29 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:29 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:29 s100-1 volumio[1089]: info: Jul 09 09:48:29 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:29 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:29 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:29 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:29 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:29 s100-1 volumio[1089]: info: ------------------------------ 7ms Jul 09 09:48:29 s100-1 volumio[1089]: info: ------------------------------ 6ms Jul 09 09:48:29 s100-1 volumio[1089]: info: ------------------------------ 4ms Jul 09 09:48:29 s100-1 volumio[1089]: info: Starting UPNP Playback Jul 09 09:48:29 s100-1 volumio[1089]: info: Preparing playback through UPNP Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:29 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:29 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 09:48:30 s100-1 volumio[1089]: info: Clearing queue after UPNP request Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreStateMachine::ClearQueue Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreStateMachine::stop Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 09:48:30 s100-1 volumio[1089]: info: CorePlayQueue::clearPlayQueue Jul 09 09:48:30 s100-1 volumio[1089]: info: CorePlayQueue::saveQueue Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:30 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:30 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushQueue Jul 09 09:48:30 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:30 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:30 s100-1 volumio[1089]: error: updateQueue error: null Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 7ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 5ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 5ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Starting UPNP Playback Jul 09 09:48:30 s100-1 volumio[1089]: info: Preparing playback through UPNP Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetState Jul 09 09:48:30 s100-1 volumio[1089]: info: CorePlayQueue::getTrack 1 Jul 09 09:48:30 s100-1 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 8ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 6ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 4ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 7ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 5ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 4ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 7ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 7ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 5ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 8ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 5ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 3ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 4ms Jul 09 09:48:30 s100-1 volumio[1089]: info: ------------------------------ 3ms Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:30 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:30 s100-1 volumio[1089]: info: Jul 09 09:48:30 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 6ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 4ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 3ms Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 3ms Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 7 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 8 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 10 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 6 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 4 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战 (Live)","artist":null,"album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战 (Live)","artist":null,"album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 55ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 61ms Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 41 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 39 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 39 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战 (Live)","artist":null,"album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战 (Live)","artist":null,"album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战 (Live)","artist":null,"album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 147ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 138ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 137ms Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 105 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces system playlist update Jul 09 09:48:31 s100-1 volumio[1089]: info: Ignoring MPD Status Update Jul 09 09:48:31 s100-1 volumio[1089]: info: Jul 09 09:48:31 s100-1 volumio[1089]: ---------------------------- MPD announces state update: player Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::getState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 26ms Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 23 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 12ms Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 10 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 9ms Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand status took 6 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挑战","artist":"刘惜君","album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 176ms Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1155 Kbps","isStreaming":false,"title":"挑战","artist":"刘惜君","album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1155 Kbps","isStreaming":false,"title":"挑战","artist":"刘惜君","album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ControllerMpd::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::servicePushState Jul 09 09:48:31 s100-1 volumio[1089]: verbose: In UPNP mode Jul 09 09:48:31 s100-1 volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":262,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1155 Kbps","isStreaming":false,"title":"挑战","artist":"刘惜君","album":"声生不息 第7期","uri":"http://116.31.99.15/amobile.music.tc.qq.com/F0000020INj93TCAwU.flac?guid=E84F924BBDD1460FB2E5B48D83E32363&vkey=1ABF31FB9ABC1E1022D26A600648AA65FC57BC3F7645A6650BAA823E392ADBD08BAC1DD1647F528379D811F0FCA2C3F66EA614B80D3A94C8&uin=675426311&src=F000001GsIC31NjtIP.flac&redirect=1&fromtag=101114","trackType":""} Jul 09 09:48:31 s100-1 volumio[1089]: verbose: CURRENT POSITION 1 Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState stateService play Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jul 09 09:48:31 s100-1 volumio[1089]: info: Received an update from plugin. extracting info from payload Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:31 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 140ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 125ms Jul 09 09:48:31 s100-1 volumio[1089]: info: ------------------------------ 121ms Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:31 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:34 s100-1 volumio[1387]: 93 Jul 09 09:48:34 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:34 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:34 s100-1 volumio[1089]: info: Jul 09 09:48:34 s100-1 volumio[1089]: ---------------------------- Client requests Volume 93 Jul 09 09:48:34 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume93 Jul 09 09:48:34 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:34 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:34 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:34 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:34 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:34 s100-1 volumio[1387]: {"time":1720489714621,"response":"volume Success"}93 Jul 09 09:48:34 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:34 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:34 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:34 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:34 s100-1 volumio[1387]: 75 Jul 09 09:48:35 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:35 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:35 s100-1 volumio[1089]: info: Jul 09 09:48:35 s100-1 volumio[1089]: ---------------------------- Client requests Volume 75 Jul 09 09:48:35 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume75 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:35 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:35 s100-1 volumio[1387]: {"time":1720489715046,"response":"volume Success"}75 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:35 s100-1 volumio[1387]: 43 Jul 09 09:48:35 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:35 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:35 s100-1 volumio[1089]: info: Jul 09 09:48:35 s100-1 volumio[1089]: ---------------------------- Client requests Volume 43 Jul 09 09:48:35 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume43 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:35 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:35 s100-1 volumio[1387]: {"time":1720489715318,"response":"volume Success"}43 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:35 s100-1 volumio[1387]: 37 Jul 09 09:48:35 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:35 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:35 s100-1 volumio[1089]: info: Jul 09 09:48:35 s100-1 volumio[1089]: ---------------------------- Client requests Volume 37 Jul 09 09:48:35 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume37 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:35 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:35 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:35 s100-1 volumio[1387]: {"time":1720489715826,"response":"volume Success"}37 Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:35 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:37 s100-1 volumio[1387]: 43 Jul 09 09:48:37 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:37 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:37 s100-1 volumio[1089]: info: Jul 09 09:48:37 s100-1 volumio[1089]: ---------------------------- Client requests Volume 43 Jul 09 09:48:37 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume43 Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:37 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:37 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:37 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:37 s100-1 volumio[1387]: {"time":1720489717386,"response":"volume Success"}43 Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:37 s100-1 volumio[1387]: 50 Jul 09 09:48:37 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:37 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:37 s100-1 volumio[1089]: info: Jul 09 09:48:37 s100-1 volumio[1089]: ---------------------------- Client requests Volume 50 Jul 09 09:48:37 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume50 Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:37 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:37 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:37 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:37 s100-1 volumio[1387]: {"time":1720489717752,"response":"volume Success"}50 Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:37 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:38 s100-1 volumio[1387]: 62 Jul 09 09:48:38 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:38 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:38 s100-1 volumio[1089]: info: Jul 09 09:48:38 s100-1 volumio[1089]: ---------------------------- Client requests Volume 62 Jul 09 09:48:38 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume62 Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:38 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:38 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:38 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:38 s100-1 volumio[1387]: {"time":1720489718170,"response":"volume Success"}62 Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:38 s100-1 volumio[1387]: 68 Jul 09 09:48:38 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:38 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:38 s100-1 volumio[1089]: info: Jul 09 09:48:38 s100-1 volumio[1089]: ---------------------------- Client requests Volume 68 Jul 09 09:48:38 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume68 Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:38 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:38 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:38 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:38 s100-1 volumio[1387]: {"time":1720489718608,"response":"volume Success"}68 Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:38 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:38 s100-1 volumio[1387]: 81 Jul 09 09:48:39 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:39 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:39 s100-1 volumio[1089]: info: Jul 09 09:48:39 s100-1 volumio[1089]: ---------------------------- Client requests Volume 81 Jul 09 09:48:39 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume81 Jul 09 09:48:39 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:39 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:39 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:39 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:39 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:39 s100-1 volumio[1387]: {"time":1720489719012,"response":"volume Success"}81 Jul 09 09:48:39 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:39 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:39 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:39 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:48:40 s100-1 volumio[1387]: 93 Jul 09 09:48:41 s100-1 volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:48:41 s100-1 volumio[1387]: Dload Upload Total Spent Left Speed Jul 09 09:48:41 s100-1 volumio[1089]: info: Jul 09 09:48:41 s100-1 volumio[1089]: ---------------------------- Client requests Volume 93 Jul 09 09:48:41 s100-1 volumio[1089]: info: VolumeController::SetAlsaVolume93 Jul 09 09:48:41 s100-1 volumio[1089]: info: CoreStateMachine::pushState Jul 09 09:48:41 s100-1 volumio[1089]: info: CoreCommandRouter::volumioPushState Jul 09 09:48:41 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output update for this device Jul 09 09:48:41 s100-1 volumio[1089]: info: MRS: Pushing multiroomSync output Jul 09 09:48:41 s100-1 volumio[1387]: [158B blob data] Jul 09 09:48:41 s100-1 volumio[1387]: {"time":1720489721019,"response":"volume Success"}93 Jul 09 09:48:41 s100-1 volumio[1089]: info: CoreCommandRouter::volumioGetQueue Jul 09 09:48:41 s100-1 volumio[1089]: info: CoreStateMachine::getQueue Jul 09 09:48:41 s100-1 volumio[1089]: info: CorePlayQueue::getQueue Jul 09 09:48:41 s100-1 volumio[1089]: info: Updating RAAT Signal Path Jul 09 09:49:34 s100-1 volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 09:49:34 s100-1 volumio[1089]: Error: connect ETIMEDOUT 104.244.43.136:443 Jul 09 09:49:34 s100-1 volumio[1089]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 09 09:49:34 s100-1 volumio[1089]: errno: -110, Jul 09 09:49:34 s100-1 volumio[1089]: code: 'ETIMEDOUT', Jul 09 09:49:34 s100-1 volumio[1089]: syscall: 'connect', Jul 09 09:49:34 s100-1 volumio[1089]: address: '104.244.43.136', Jul 09 09:49:34 s100-1 volumio[1089]: port: 443 Jul 09 09:49:34 s100-1 volumio[1089]: } Jul 09 09:49:34 s100-1 volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 09:49:36 s100-1 sudo[2414]: volumio : unable to resolve host s100-1 Jul 09 09:49:36 s100-1 sudo[2414]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 09 09:49:36 s100-1 sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 09:48 Jul 09 09:49:36 s100-1 sudo[2414]: 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="616c59d0809da875c5b18d0362c4993292d5d04e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 27 May 2024 10:50:11 AM CEST" VOLUMIO_VERSION="3.683" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="9eb7c6308a02bc314c2bcf14e1ba772e"