-- Logs begin at Fri 2024-07-19 19:52:21 CST, end at Fri 2024-07-19 20:18:22 CST. -- Jul 19 20:18:13 volumio volumio[1133]: info: Clearing queue after UPNP request Jul 19 20:18:13 volumio volumio[1133]: verbose: UNSET VOLATILE: Service: airplay_emulation Jul 19 20:18:13 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 19 20:18:13 volumio sudo[8904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2021 Jul 19 20:18:13 volumio sudo[8904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:13 volumio sudo[8904]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:13 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Jul 19 20:18:13 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio shairport-sync[2021]: {"time":1721390757931,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 20:18:13 volumio shairport-sync[2021]: Dload Upload Total Spent Left Speed Jul 19 20:18:13 volumio volumio[1133]: info: Jul 19 20:18:13 volumio volumio[1133]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 19 20:18:13 volumio volumio[1133]: info: Airplay playback start Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio shairport-sync[2021]: [158B blob data] Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:13 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Jul 19 20:18:13 volumio volumio[1133]: info: CorePlayQueue::saveQueue Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:13 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Jul 19 20:18:13 volumio volumio[1133]: error: Upnp client error: Error: This socket has been ended by the other party Jul 19 20:18:14 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Jul 19 20:18:14 volumio sudo[8917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:18:14 volumio sudo[8917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:18:14 volumio shairport-sync[2021]: {"time":1721391493099,"response":"startAirplayPlayback Success"} Jul 19 20:18:14 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:18:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:14 volumio sudo[8917]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:14 volumio volumio[1133]: info: Shairport-Sync retarted Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:14 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: Starting UPNP Playback Jul 19 20:18:15 volumio volumio[1133]: info: Preparing playback through UPNP Jul 19 20:18:15 volumio volumio[1133]: verbose: UNSET VOLATILE: Service: airplay_emulation Jul 19 20:18:15 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 19 20:18:15 volumio sudo[8926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8919 Jul 19 20:18:15 volumio sudo[8926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:15 volumio sudo[8926]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:15 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:15 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:15 volumio volumio[1683]: 88 Jul 19 20:18:15 volumio volumio[1683]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 20:18:15 volumio volumio[1683]: Dload Upload Total Spent Left Speed Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- Client requests Volume 88 Jul 19 20:18:15 volumio volumio[1133]: info: VolumeController::SetAlsaVolume88 Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1683]: [158B blob data] Jul 19 20:18:15 volumio volumio[1683]: {"time":1721391495534,"response":"volume Success"}88 Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:15 volumio volumio[1133]: info: Jul 19 20:18:15 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:15 volumio volumio[1133]: 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 19 20:18:15 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:15 volumio volumio[1133]: 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 19 20:18:15 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: No code Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand status took 12 milliseconds Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand clearerror took 10 milliseconds Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand clearerror took 10 milliseconds Jul 19 20:18:15 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:15 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:15 volumio volumio[1133]: 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 19 20:18:15 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: No code Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:15 volumio volumio[1133]: info: ------------------------------ 16ms Jul 19 20:18:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:15 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:15 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:15 volumio volumio[1133]: info: sendMpdCommand clearerror took 6 milliseconds Jul 19 20:18:15 volumio volumio[1133]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/%E5%A4%A7%E5%9C%B0/ed51aecb-1d19-4b7d-8164-4dddb9206436.jpg' Jul 19 20:18:16 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Jul 19 20:18:16 volumio sudo[8943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:18:16 volumio sudo[8943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:18:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:18:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:16 volumio sudo[8943]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:16 volumio volumio[1133]: info: Shairport-Sync retarted Jul 19 20:18:16 volumio volumio[1133]: info: Clearing queue after UPNP request Jul 19 20:18:16 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Jul 19 20:18:16 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:16 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:16 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Jul 19 20:18:16 volumio volumio[1133]: info: CorePlayQueue::saveQueue Jul 19 20:18:16 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:16 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Jul 19 20:18:16 volumio volumio[1133]: info: Jul 19 20:18:16 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:16 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:16 volumio volumio[1133]: info: Jul 19 20:18:16 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:16 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:16 volumio volumio[1133]: info: Jul 19 20:18:16 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:16 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:16 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:16 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:16 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:16 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:16 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:16 volumio volumio[1133]: info: Starting UPNP Playback Jul 19 20:18:16 volumio volumio[1133]: info: Preparing playback through UPNP Jul 19 20:18:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Jul 19 20:18:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:16 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:17 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:17 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 3ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 4ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:17 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:17 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 12 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:17 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 45ms Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"104 Kbps","isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:17 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 46ms Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:17 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::serviceStop Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::stop Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand stop took 2 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: No code Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: No code Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝你一路顺风","artist":"吴奇隆","album":"追风少年","uri":"http://192.168.123.85:34527/bridge-5.flac","trackType":"flac"} Jul 19 20:18:17 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio volumio[1133]: info: No code Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:17 volumio shairport-sync[8945]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:17 volumio shairport-sync[8945]: Dload Upload Total Spent Left Speed Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 14ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 14ms Jul 19 20:18:17 volumio volumio[1133]: info: ------------------------------ 13ms Jul 19 20:18:17 volumio volumio[1133]: info: Jul 19 20:18:17 volumio volumio[1133]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 19 20:18:17 volumio volumio[1133]: info: Airplay playback start Jul 19 20:18:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:17 volumio volumio[1133]: info: CoreCommandRouter::volumioStop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:17 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:17 volumio shairport-sync[8945]: [158B blob data] Jul 19 20:18:18 volumio volumio[1133]: info: Clearing queue after UPNP request Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio shairport-sync[8945]: {"time":1721391497730,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 20:18:18 volumio shairport-sync[8945]: Dload Upload Total Spent Left Speed Jul 19 20:18:18 volumio volumio[1133]: info: Jul 19 20:18:18 volumio volumio[1133]: ---------------------------- Client requests Stop Airplay Playback Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jul 19 20:18:18 volumio volumio[1133]: info: Airplay playback stop Jul 19 20:18:18 volumio volumio[1133]: info: Airplay Playback Stopped, clearing state Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::resetVolumioState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::getcurrentVolume Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:18:18 volumio shairport-sync[8945]: [158B blob data] Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: VolumeController:: Volume=85 Mute =false Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock Jul 19 20:18:18 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:18:18 volumio volumio[1133]: info: VolumeController:: Volume=85 Mute =false Jul 19 20:18:18 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:18 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:18 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:18 volumio volumio[1133]: info: Airplay started streaming, receiving metadatas Jul 19 20:18:18 volumio shairport-sync[8945]: {"time":1721391498727,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 20:18:18 volumio shairport-sync[8945]: Dload Upload Total Spent Left Speed Jul 19 20:18:18 volumio volumio[1133]: info: Jul 19 20:18:18 volumio volumio[1133]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 19 20:18:18 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 19 20:18:18 volumio volumio[1133]: info: Airplay playback start Jul 19 20:18:18 volumio shairport-sync[8945]: [158B blob data] Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::serviceStop Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::serviceStop Jul 19 20:18:19 volumio volumio[1133]: info: Airplay Stop Jul 19 20:18:19 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::saveQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:19 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:19 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: Starting UPNP Playback Jul 19 20:18:19 volumio volumio[1133]: info: Preparing playback through UPNP Jul 19 20:18:19 volumio volumio[1133]: verbose: UNSET VOLATILE: Service: airplay_emulation Jul 19 20:18:19 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 19 20:18:19 volumio sudo[9003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8945 Jul 19 20:18:19 volumio sudo[9003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 19 20:18:19 volumio sudo[9003]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:19 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Jul 19 20:18:19 volumio sudo[9007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8945 Jul 19 20:18:19 volumio sudo[9007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:19 volumio sudo[9007]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:19 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:19 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 24 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: info: Jul 19 20:18:19 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 31 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 31 milliseconds Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 8 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 46ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 47ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 47ms Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 19 20:18:19 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:19 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"爱与痛的边缘","artist":"王菲","album":"王菲的故事","uri":"http://192.168.123.85:42217/bridge-6.flac","trackType":"flac"} Jul 19 20:18:19 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:19 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 31ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 24ms Jul 19 20:18:19 volumio volumio[1133]: info: ------------------------------ 24ms Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:19 volumio volumio[1133]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/%E7%8E%8B%E8%8F%B2%E7%9A%84%E6%95%85%E4%BA%8B/b5208b53-8284-4775-a6a6-1e5535a35a51.jpg' Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:19 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Jul 19 20:18:20 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Jul 19 20:18:20 volumio sudo[9017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:18:20 volumio sudo[9017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:18:20 volumio shairport-sync[8945]: {"time":1721391498845,"response":"startAirplayPlayback Success"} Jul 19 20:18:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:18:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:20 volumio sudo[9020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:18:20 volumio sudo[9020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:20 volumio sudo[9017]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:20 volumio volumio[1133]: info: Shairport-Sync retarted Jul 19 20:18:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:18:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 19 20:18:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:18:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:20 volumio sudo[9020]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:20 volumio volumio[1133]: info: Shairport-Sync retarted Jul 19 20:18:20 volumio volumio[1133]: info: Jul 19 20:18:20 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:20 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:20 volumio volumio[1133]: info: Jul 19 20:18:20 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:20 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:20 volumio volumio[1133]: info: Jul 19 20:18:20 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:20 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:20 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:20 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:20 volumio volumio[1133]: 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 19 20:18:20 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:20 volumio volumio[1133]: 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 19 20:18:20 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: No code Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: ------------------------------ 6ms Jul 19 20:18:20 volumio volumio[1133]: info: ------------------------------ 5ms Jul 19 20:18:20 volumio volumio[1133]: info: sendMpdCommand status took 6 milliseconds Jul 19 20:18:20 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:20 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:20 volumio volumio[1133]: 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 19 20:18:20 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: No code Jul 19 20:18:20 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:20 volumio volumio[1133]: info: ------------------------------ 9ms Jul 19 20:18:21 volumio volumio[1133]: info: Clearing queue after UPNP request Jul 19 20:18:21 volumio volumio[1133]: verbose: UNSET VOLATILE: Service: undefined Jul 19 20:18:21 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 19 20:18:21 volumio sudo[9035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 9023 Jul 19 20:18:21 volumio sudo[9035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:21 volumio sudo[9035]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:21 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::stop Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::saveQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:21 volumio volumio[1133]: error: updateQueue error: null Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Starting UPNP Playback Jul 19 20:18:21 volumio volumio[1133]: info: Preparing playback through UPNP Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 1ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Jul 19 20:18:21 volumio volumio[1133]: info: Ignoring MPD Status Update Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 0ms Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: info: Jul 19 20:18:21 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:21 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:21 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 4ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 6ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 6ms Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand status took 5 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:21 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:21 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 11ms Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 19 20:18:21 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Jul 19 20:18:21 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:21 volumio volumio[1133]: verbose: In UPNP mode Jul 19 20:18:21 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"圣诞星 (feat. 杨瑞代)","artist":"周杰伦","album":"圣诞星 (feat. 杨瑞代)","uri":"http://192.168.123.85:33653/bridge-7.flac","trackType":"flac"} Jul 19 20:18:21 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:21 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:21 volumio volumio[1133]: info: ------------------------------ 14ms Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:22 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Jul 19 20:18:22 volumio sudo[9044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:18:22 volumio sudo[9044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:18:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:18:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:18:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:18:22 volumio sudo[9044]: pam_unix(sudo:session): session closed for user root Jul 19 20:18:22 volumio volumio[1133]: info: Shairport-Sync retarted Jul 19 20:18:22 volumio volumio[1133]: info: Jul 19 20:18:22 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:22 volumio volumio[1133]: info: Jul 19 20:18:22 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:22 volumio volumio[1133]: info: Jul 19 20:18:22 volumio volumio[1133]: ---------------------------- MPD announces state update: player Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::getState Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Jul 19 20:18:22 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds Jul 19 20:18:22 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:22 volumio volumio[1133]: 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 19 20:18:22 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:22 volumio volumio[1133]: 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 19 20:18:22 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: No code Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: ------------------------------ 7ms Jul 19 20:18:22 volumio volumio[1133]: info: ------------------------------ 7ms Jul 19 20:18:22 volumio volumio[1133]: info: sendMpdCommand status took 6 milliseconds Jul 19 20:18:22 volumio volumio[1133]: verbose: ControllerMpd::parseState Jul 19 20:18:22 volumio volumio[1133]: info: ControllerMpd::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Jul 19 20:18:22 volumio volumio[1133]: 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 19 20:18:22 volumio volumio[1133]: verbose: CURRENT POSITION 0 Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: No code Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::pushState Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Jul 19 20:18:22 volumio volumio[1133]: info: ------------------------------ 10ms Jul 19 20:18:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:18:22 volumio volumio[1133]: info: CoreStateMachine::getQueue Jul 19 20:18:22 volumio volumio[1133]: info: CorePlayQueue::getQueue Jul 19 20:18:22 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 20:18:22 volumio volumio[1133]: Error: connect ECONNREFUSED 4.78.139.50:443 Jul 19 20:18:22 volumio volumio[1133]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 19 20:18:22 volumio volumio[1133]: errno: -111, Jul 19 20:18:22 volumio volumio[1133]: code: 'ECONNREFUSED', Jul 19 20:18:22 volumio volumio[1133]: syscall: 'connect', Jul 19 20:18:22 volumio volumio[1133]: address: '4.78.139.50', Jul 19 20:18:22 volumio volumio[1133]: port: 443 Jul 19 20:18:22 volumio volumio[1133]: } Jul 19 20:18:22 volumio volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 20:18:22 volumio sudo[9062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-19 20:17 Jul 19 20:18:22 volumio sudo[9062]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"