-- Logs begin at Sat 2024-08-31 22:50:49 CST, end at Sat 2024-08-31 23:25:05 CST. -- Aug 31 23:24:12 volumio volumio[805]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::volumioGetState Aug 31 23:24:12 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:12 volumio volumio[805]: info: Restoring Previous Volume level: 100 false false Aug 31 23:24:12 volumio volumio[805]: info: Disable softmixer device for audio device Aug 31 23:24:12 volumio volumio[805]: info: Output device has changed, restarting MPD Aug 31 23:24:12 volumio volumio[805]: info: Output device has changed, restarting Shairport Sync Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:24:12 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:24:12 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio sudo[4132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:24:12 volumio sudo[4132]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 23:24:12 volumio volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:24:12 volumio volumio[805]: info: Volume configurations have been set Aug 31 23:24:12 volumio systemd[1]: mpd.service: Succeeded. Aug 31 23:24:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 23:24:12 volumio volumio[805]: info: Updating Volume Controller Parameters: Device: 2 Name: Nowna Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Aug 31 23:24:12 volumio systemd[1]: Starting Music Player Daemon... Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 23:24:12 volumio volumio[805]: info: Disabling external Volume Control Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 23:24:12 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:12 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:12 volumio volumio[805]: info: Soft Volume ALSA configuration file deleted Aug 31 23:24:12 volumio volumio[805]: info: Preparing to generate the ALSA configuration file Aug 31 23:24:12 volumio volumio[805]: info: MPD Permissions set Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 23:24:12 volumio volumio[805]: info: Starting Shairport Sync Aug 31 23:24:12 volumio volumio[805]: info: Asound.conf file written Aug 31 23:24:12 volumio sudo[4155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:24:12 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio sudo[4158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 23:24:12 volumio sudo[4158]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 23:24:12 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 23:24:12 volumio sudo[4158]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 23:24:12 volumio volumio[805]: info: Output device has changed, restarting MPD Aug 31 23:24:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 23:24:12 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio volumio[805]: info: Output device has changed, restarting Shairport Sync Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:24:12 volumio sudo[4164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:24:12 volumio sudo[4164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio sudo[4164]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:24:12 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:24:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 31 23:24:12 volumio systemd[1]: mpd.service: Succeeded. Aug 31 23:24:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 23:24:12 volumio volumio[805]: info: MPD Permissions set Aug 31 23:24:12 volumio volumio[805]: info: Shairport-Sync Started Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio systemd[1]: Starting Music Player Daemon... Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:24:12 volumio volumio[805]: info: Starting Shairport Sync Aug 31 23:24:12 volumio sudo[4180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:24:12 volumio sudo[4180]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 23:24:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 23:24:12 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 23:24:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 23:24:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 23:24:12 volumio sudo[4180]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio volumio[805]: info: Shairport-Sync Started Aug 31 23:24:12 volumio mpd[4174]: Aug 31 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 23:24:12 volumio systemd[1]: Started Music Player Daemon. Aug 31 23:24:12 volumio sudo[4132]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root Aug 31 23:24:12 volumio volumio[805]: error: MPD error: The expression evaluated to a falsy value: Aug 31 23:24:12 volumio volumio[805]: assert.ok(self.idling) Aug 31 23:24:12 volumio volumio[805]: error: The expression evaluated to a falsy value: Aug 31 23:24:12 volumio volumio[805]: assert.ok(self.idling) Aug 31 23:24:12 volumio volumio[805]: error: updateQueue error: null Aug 31 23:24:14 volumio volumio[805]: info: CoreCommandRouter::volumioSeek Aug 31 23:24:14 volumio volumio[805]: info: CoreStateMachine::seek Aug 31 23:24:14 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:14 volumio volumio[805]: info: TRACKBLOCK {"uri":"mnt/USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","service":"mpd","name":"1 - 离开以后","artist":"张学友","album":"友情歌岁月精选","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=619&web=%E5%BC%A0%E5%AD%A6%E5%8F%8B/%E5%8F%8B%E6%83%85%E6%AD%8C%E5%B2%81%E6%9C%88%E7%B2%BE%E9%80%89/extralarge&path=%2FUSB%2Fmusic%2FMusic%2F%E3%80%90WAV%E3%80%81FLAC%E3%80%81ISO%E3%80%91%2F%E6%B8%AF%E5%8F%B0%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%2F%E5%8F%8B%E6%83%85%E6%AD%8C%E6%AD%B2%E6%9C%88&metadata=false","duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Aug 31 23:24:14 volumio volumio[805]: info: CoreStateMachine::startPlaybackTimer Aug 31 23:24:14 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:14 volumio volumio[805]: info: ControllerMpd::seek Aug 31 23:24:14 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:14 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:14 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:14 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:14 volumio volumio[805]: error: [2@0] {seek} Bad song index Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPlay Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::play index 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::stop Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::stPlaybackTimer Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::updateTrackBlock Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrackBlock Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::serviceStop Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::serviceStop Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::stop Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand stop took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::play index undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::startPlaybackTimer Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand stop took 0 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand clear took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav" Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: error: updateQueue error: null Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 1ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand add "USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav" took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 1ms Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand play Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 2ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand play took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 1ms Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 6 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 6 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"拉阔音乐会2001","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus stop Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"拉阔音乐会2001","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 12ms Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 12ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"拉阔音乐会2001","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"拉阔音乐会2001","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/拉阔音乐会/18. 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 12ms Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 10ms Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPlay Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::play index 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::stop Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::stPlaybackTimer Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::updateTrackBlock Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrackBlock Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::serviceStop Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 1 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::serviceStop Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::stop Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand stop took 34 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::play index undefined Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::startPlaybackTimer Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand stop took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand clear took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav" Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: 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} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService stop Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus stop Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: No code Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 9ms Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: error: updateQueue error: null Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 10ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand add "USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav" took 8 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 2ms Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand play Aug 31 23:24:15 volumio volumio[805]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 13ms Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces system playlist update Aug 31 23:24:15 volumio volumio[805]: info: Ignoring MPD Status Update Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 6ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand play took 4 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 4ms Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 2 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: info: Aug 31 23:24:15 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand status took 0 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus stop Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 6ms Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 6ms Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 23:24:15 volumio volumio[805]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:15 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:15 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:15 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:15 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 8ms Aug 31 23:24:15 volumio volumio[805]: info: ------------------------------ 9ms Aug 31 23:24:16 volumio volumio[805]: info: VolumeController::SetAlsaVolume100 Aug 31 23:24:16 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:16 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:16 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:16 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioSeek Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::seek Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: error: null Aug 31 23:24:17 volumio volumio[805]: info: Aug 31 23:24:17 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:17 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:17 volumio volumio[805]: info: Aug 31 23:24:17 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:17 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:17 volumio volumio[805]: info: sendMpdCommand status took 2 milliseconds Aug 31 23:24:17 volumio volumio[805]: info: sendMpdCommand status took 1 milliseconds Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:17 volumio volumio[805]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 23:24:17 volumio volumio[805]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:17 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:17 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:17 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:17 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:17 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::syncState stateService play Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus play Aug 31 23:24:17 volumio volumio[805]: info: Received an update from plugin. extracting info from payload Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:17 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:17 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:17 volumio volumio[805]: info: ------------------------------ 9ms Aug 31 23:24:17 volumio volumio[805]: info: ------------------------------ 8ms Aug 31 23:24:26 volumio volumio[805]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 31 23:24:26 volumio volumio[805]: info: Retrying Login Due to Network Error Aug 31 23:24:36 volumio volumio[805]: info: MyVolumio login type: Token Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::volumioPause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::pause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::stPlaybackTimer Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::servicePause Aug 31 23:24:59 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::servicePause Aug 31 23:24:59 volumio volumio[805]: info: ControllerMpd::pause Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 23:24:59 volumio volumio[805]: info: Aug 31 23:24:59 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:59 volumio volumio[805]: info: sendMpdCommand pause took 0 milliseconds Aug 31 23:24:59 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:59 volumio volumio[805]: info: sendMpdCommand status took 0 milliseconds Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:59 volumio volumio[805]: info: Aug 31 23:24:59 volumio volumio[805]: ---------------------------- MPD announces state update: player Aug 31 23:24:59 volumio volumio[805]: info: ControllerMpd::getState Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand status Aug 31 23:24:59 volumio volumio[805]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 23:24:59 volumio volumio[805]: info: sendMpdCommand status took 1 milliseconds Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::parseState Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 23:24:59 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:59 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:59 volumio volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":104105,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:59 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::syncState stateService pause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus pause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:59 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::stPlaybackTimer Aug 31 23:24:59 volumio volumio[805]: info: ------------------------------ 4ms Aug 31 23:24:59 volumio volumio[805]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 23:24:59 volumio volumio[805]: verbose: ControllerMpd::parseTrackInfo Aug 31 23:24:59 volumio volumio[805]: info: ControllerMpd::pushState Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::servicePushState Aug 31 23:24:59 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:59 volumio volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":104105,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"离开以后","artist":"张学友","album":"友情歌岁月精选","uri":"USB/music/Music/【WAV、FLAC、ISO】/港台/张学友/友情歌歲月/01 离开以后.wav","trackType":"wav"} Aug 31 23:24:59 volumio volumio[805]: verbose: CURRENT POSITION 0 Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::syncState stateService pause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::syncState currentStatus pause Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::pushState Aug 31 23:24:59 volumio volumio[805]: info: CorePlayQueue::getTrack 0 Aug 31 23:24:59 volumio volumio[805]: info: CoreCommandRouter::volumioPushState Aug 31 23:24:59 volumio volumio[805]: info: CoreStateMachine::stPlaybackTimer Aug 31 23:24:59 volumio volumio[805]: info: ------------------------------ 5ms Aug 31 23:25:05 volumio volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:25:05 volumio volumio[805]: Error: connect ETIMEDOUT 192.133.77.197:80 Aug 31 23:25:05 volumio volumio[805]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 31 23:25:05 volumio volumio[805]: errno: -110, Aug 31 23:25:05 volumio volumio[805]: code: 'ETIMEDOUT', Aug 31 23:25:05 volumio volumio[805]: syscall: 'connect', Aug 31 23:25:05 volumio volumio[805]: address: '192.133.77.197', Aug 31 23:25:05 volumio volumio[805]: port: 80 Aug 31 23:25:05 volumio volumio[805]: } Aug 31 23:25:05 volumio volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:25:05 volumio sudo[4242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 23:24 Aug 31 23:25:05 volumio sudo[4242]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"