-- 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"