-- Logs begin at Fri 2024-08-23 16:11:54 CST, end at Fri 2024-08-23 16:14:31 CST. -- Aug 23 16:13:04 volumio ntpd[994]: Soliciting pool server 193.182.111.14 Aug 23 16:13:05 volumio volumio[1213]: info: MyVolumio login type: Token Aug 23 16:13:05 volumio ntpd[994]: Soliciting pool server 193.182.111.143 Aug 23 16:13:06 volumio ntpd[994]: Soliciting pool server 202.112.31.197 Aug 23 16:13:06 volumio ntpd[994]: Soliciting pool server 202.118.1.81 Aug 23 16:13:07 volumio ntpd[994]: Soliciting pool server 162.159.200.1 Aug 23 16:13:07 volumio ntpd[994]: Soliciting pool server 78.46.102.180 Aug 23 16:13:07 volumio ntpd[994]: Soliciting pool server 193.182.111.142 Aug 23 16:13:07 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:07 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:07 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:07 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:07 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:07 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:07 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:07 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:07 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:07 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:07 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:13:08 volumio ntpd[994]: Soliciting pool server 81.16.177.123 Aug 23 16:13:08 volumio ntpd[994]: Soliciting pool server 108.59.2.24 Aug 23 16:13:09 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:09 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:09 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:09 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:09 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:09 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:09 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:09 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:09 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:13:10 volumio ntpd[994]: Soliciting pool server 119.28.206.193 Aug 23 16:13:10 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:10 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:10 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:10 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:10 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:10 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:10 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:10 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:10 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:13:11 volumio ntpd[994]: Soliciting pool server 84.16.67.12 Aug 23 16:13:12 volumio ntpd[994]: Soliciting pool server 111.203.6.13 Aug 23 16:13:13 volumio volumio[1213]: info: CoreCommandRouter::volumioSeek Aug 23 16:13:13 volumio volumio[1213]: info: CoreStateMachine::seek Aug 23 16:13:13 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:13 volumio volumio[1213]: info: TRACKBLOCK {"uri":"mnt/hdd1/沙溢 _ 谭晶 - 因为爱情 (Live)_hires.flac","service":"mpd","name":"因为爱情 (Live)","artist":"沙溢/谭晶","album":"《我们的挑战》新春歌会特辑","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=686&web=%E6%B2%99%E6%BA%A2%2F%E8%B0%AD%E6%99%B6/%E3%80%8A%E6%88%91%E4%BB%AC%E7%9A%84%E6%8C%91%E6%88%98%E3%80%8B%E6%96%B0%E6%98%A5%E6%AD%8C%E4%BC%9A%E7%89%B9%E8%BE%91/extralarge&path=%2Fhdd1&metadata=false","duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Aug 23 16:13:13 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:13 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:13 volumio volumio[1213]: info: ControllerMpd::seek Aug 23 16:13:13 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:13:13 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:13 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:13:13 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:13:13 volumio volumio[1213]: error: [2@0] {seek} Bad song index Aug 23 16:13:16 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:16 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:16 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:16 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:16 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:16 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:16 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:16 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:16 volumio volumio[1213]: info: sendMpdCommand play took 1 milliseconds Aug 23 16:13:27 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 16:13:27 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:27 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:27 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 16:13:27 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:27 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:27 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.209 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 16:13:27 volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 16:13:27 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:27 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:27 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 16:13:27 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:27 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:27 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.209 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:27 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 16:13:27 volumio volumio[1213]: info: Received Get System Info Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 16:13:27 volumio volumio[1213]: info: Discovery: Getting this device information Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:27 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:27 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:27 volumio volumio[1213]: info: Listing playlists Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 16:13:27 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 16:13:28 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:28 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 16:13:29 volumio volumio[1213]: info: Received Get System Info Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 16:13:29 volumio volumio[1213]: info: Discovery: Getting this device information Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:29 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:29 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 16:13:30 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:30 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:30 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:30 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:30 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:30 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:30 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:30 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:30 volumio volumio[1213]: info: sendMpdCommand play took 1 milliseconds Aug 23 16:13:31 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:13:31 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:13:31 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:13:31 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:31 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:13:31 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:31 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:13:31 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:13:31 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:13:34 volumio volumio[1213]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 23 16:13:34 volumio volumio[1213]: info: Retrying Login Due to Network Error Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 16:13:36 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 16:13:37 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 23 16:13:44 volumio volumio[1213]: info: MyVolumio login type: Token Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 16:13:45 volumio volumio[1213]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 23 16:13:45 volumio volumio[1213]: info: Received Get System Version Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 16:13:45 volumio volumio[1213]: info: Received Get System Info Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 16:13:45 volumio volumio[1213]: info: Discovery: Getting this device information Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:13:45 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:13:45 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 16:13:57 volumio volumio[1213]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 23 16:13:57 volumio sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 16:13:57 volumio sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio sudo[1651]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 16:13:57 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:13:57 volumio volumio[1213]: info: MPD Permissions set Aug 23 16:13:57 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 16:13:57 volumio systemd[1]: mpd.service: Succeeded. Aug 23 16:13:57 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 16:13:57 volumio systemd[1]: Starting Music Player Daemon... Aug 23 16:13:57 volumio sudo[1660]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 16:13:57 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:13:57 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:57 volumio mpd[1662]: Aug 23 16:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 16:13:57 volumio systemd[1]: Started Music Player Daemon. Aug 23 16:13:57 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Aug 23 16:13:57 volumio volumio[1213]: error: updateQueue error: null Aug 23 16:14:02 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:02 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:02 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:02 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:02 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:02 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:02 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:14:02 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:02 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:14:03 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:14:03 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:03 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:14:03 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:14:03 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:03 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:14:03 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:03 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:03 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:14:03 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:03 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:14:07 volumio volumio[1213]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 23 16:14:07 volumio volumio[1213]: info: Preparing to save Alsa Options, stopping services first Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:14:07 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::volumioPause Aug 23 16:14:07 volumio volumio[1213]: info: CoreStateMachine::pause Aug 23 16:14:07 volumio volumio[1213]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 16:14:07 volumio volumio[1213]: info: Setting mixer PCM for card Combo384 Amanero Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 23 16:14:07 volumio volumio[1213]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 23 16:14:07 volumio volumio[1213]: info: Disabling external Volume Control Aug 23 16:14:07 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 16:14:08 volumio volumio[1213]: info: Preparing to generate the ALSA configuration file Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 16:14:08 volumio volumio[1213]: info: Asound.conf file unchanged, so no further update is needed Aug 23 16:14:08 volumio volumio[1213]: info: Output device has changed, restarting MPD Aug 23 16:14:08 volumio volumio[1213]: info: Output device has changed, restarting Shairport Sync Aug 23 16:14:08 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 16:14:08 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:14:08 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Aug 23 16:14:08 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 16:14:08 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:14:08 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 16:14:08 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 16:14:08 volumio systemd[1]: mpd.service: Succeeded. Aug 23 16:14:08 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 16:14:08 volumio volumio[1213]: info: MPD Permissions set Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 16:14:08 volumio volumio[1213]: info: VolumeController:: Volume=46 Mute =false Aug 23 16:14:08 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:08 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:08 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:08 volumio systemd[1]: Starting Music Player Daemon... Aug 23 16:14:08 volumio volumio[1213]: info: Starting Shairport Sync Aug 23 16:14:08 volumio sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 16:14:08 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:14:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 16:14:08 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 16:14:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 16:14:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 16:14:08 volumio sudo[1725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 16:14:08 volumio sudo[1725]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 16:14:08 volumio sudo[1725]: pam_unix(sudo:session): session closed for user root Aug 23 16:14:08 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root Aug 23 16:14:08 volumio volumio[1213]: info: Shairport-Sync Started Aug 23 16:14:08 volumio mpd[1736]: Aug 23 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 16:14:08 volumio systemd[1]: Started Music Player Daemon. Aug 23 16:14:08 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root Aug 23 16:14:08 volumio volumio[1213]: error: updateQueue error: null Aug 23 16:14:11 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 23 16:14:11 volumio volumio[1213]: info: CoreStateMachine::getcurrentVolume Aug 23 16:14:11 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 16:14:12 volumio volumio[1213]: info: VolumeController:: Volume=46 Mute =false Aug 23 16:14:12 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:12 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:12 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:12 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:12 volumio volumio[1213]: info: CoreStateMachine::updateTrackBlock Aug 23 16:14:12 volumio volumio[1213]: info: CorePlayQueue::getTrackBlock Aug 23 16:14:12 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 16:14:12 volumio volumio[1213]: info: VolumeController:: Volume=46 Mute =false Aug 23 16:14:12 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:12 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:12 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:12 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:14 volumio volumio[1213]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 23 16:14:14 volumio volumio[1213]: info: Retrying Login Due to Network Error Aug 23 16:14:15 volumio volumio[1213]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:14:15 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:15 volumio volumio[1213]: info: Restoring Previous Volume level: 46 false false Aug 23 16:14:15 volumio volumio[1213]: info: Volume configurations have been set Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 23 16:14:15 volumio volumio[1213]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM,1 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 23 16:14:15 volumio volumio[1213]: info: Disabling external Volume Control Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 16:14:15 volumio volumio[1213]: info: VolumeController:: Volume=46 Mute =false Aug 23 16:14:15 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:15 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:15 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:17 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:17 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:17 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:17 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:17 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:17 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:17 volumio volumio[1213]: info: ControllerMpd::resume Aug 23 16:14:17 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:17 volumio volumio[1213]: info: sendMpdCommand play took 0 milliseconds Aug 23 16:14:19 volumio volumio[1213]: info: VolumeController::SetAlsaVolume46 Aug 23 16:14:19 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:19 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:19 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:19 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:20 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:14:20 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:24 volumio volumio[1213]: info: CoreCommandRouter::volumioGetQueue Aug 23 16:14:24 volumio volumio[1213]: info: CoreStateMachine::getQueue Aug 23 16:14:24 volumio volumio[1213]: info: CorePlayQueue::getQueue Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Tsai%20Chin/04cd4d95-427f-43b5-a3a2-33d833ce49ff.jpg' Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/M2M/Shades%20of%20Purple/3a06d488-e409-4ebc-8a8c-6152c2bfba0e.png' Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Richard%20Marx/Repeat%20Offender/c373bad0-9795-48e9-8894-12bd1d9a8fe3.jpg' Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lionel%20Richie/Back%20To%20Front/108ecaa1-3cc0-46f7-94d4-425a125bfe35.jpg' Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sarah%20Brightman/La%20Luna/e6eeee1d-2a1e-4c57-9a60-99ee886bf1a3.jpg' Aug 23 16:14:24 volumio volumio[1213]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%80%83%E8%B7%91%E8%AE%A1%E5%88%92/%E4%B8%96%E7%95%8C/e107e987-b325-4c10-a301-8c2a16abf884.jpg' Aug 23 16:14:24 volumio volumio[1213]: info: MyVolumio login type: Token Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::play index 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::stop Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::updateTrackBlock Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrackBlock Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::stPlaybackTimer Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::serviceStop Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::serviceStop Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::stop Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand stop took 2 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::clearAddPlayTracks hdd1/张碧晨 - 她说 (Live)hires.flac Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand stop took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces system playlist update Aug 23 16:14:25 volumio volumio[1213]: info: Ignoring MPD Status Update Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand clear took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand add "hdd1/张碧晨 - 她说 (Live)hires.flac" Aug 23 16:14:25 volumio volumio[1213]: error: updateQueue error: null Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces system playlist update Aug 23 16:14:25 volumio volumio[1213]: info: Ignoring MPD Status Update Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 3ms Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand add "hdd1/张碧晨 - 她说 (Live)hires.flac" took 3 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 1ms Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand play took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces state update: player Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::getState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand status Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces state update: player Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand status took 32 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::getState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand status Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand status took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseTrackInfo Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"927 Kbps","isStreaming":false,"title":"她说 (Live)","artist":"张碧晨","album":"中国好声音第三季 第2期","uri":"hdd1/张碧晨 - 她说 (Live)hires.flac","trackType":"flac"} Aug 23 16:14:25 volumio volumio[1213]: verbose: CURRENT POSITION 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState stateService play Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus stop Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 35ms Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseTrackInfo Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: STATE SERVICE {"status":"play","position":0,"seek":661,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"967 Kbps","isStreaming":false,"title":"她说 (Live)","artist":"张碧晨","album":"中国好声音第三季 第2期","uri":"hdd1/张碧晨 - 她说 (Live)hires.flac","trackType":"flac"} Aug 23 16:14:25 volumio volumio[1213]: verbose: CURRENT POSITION 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState stateService play Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus play Aug 23 16:14:25 volumio volumio[1213]: info: Received an update from plugin. extracting info from payload Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 10ms Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPlay Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::play index 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::stop Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::stPlaybackTimer Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::updateTrackBlock Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrackBlock Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::serviceStop Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::serviceStop Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::stop Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces state update: player Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand stop took 4 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::getState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand status Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::play index undefined Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::startPlaybackTimer Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::clearAddPlayTracks hdd1/张碧晨 - 她说 (Live)hires.flac Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand status took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand stop took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces system playlist update Aug 23 16:14:25 volumio volumio[1213]: info: Ignoring MPD Status Update Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand clear took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseTrackInfo Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand add "hdd1/张碧晨 - 她说 (Live)hires.flac" Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: 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":"她说 (Live)","artist":"张碧晨","album":"中国好声音第三季 第2期","uri":"hdd1/张碧晨 - 她说 (Live)hires.flac","trackType":"flac"} Aug 23 16:14:25 volumio volumio[1213]: verbose: CURRENT POSITION 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState stateService stop Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus stop Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: No code Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 7ms Aug 23 16:14:25 volumio volumio[1213]: error: updateQueue error: null Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces system playlist update Aug 23 16:14:25 volumio volumio[1213]: info: Ignoring MPD Status Update Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 7ms Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand add "hdd1/张碧晨 - 她说 (Live)hires.flac" took 7 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand play Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 1ms Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces state update: player Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand play took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::getState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand status Aug 23 16:14:25 volumio volumio[1213]: info: Aug 23 16:14:25 volumio volumio[1213]: ---------------------------- MPD announces state update: player Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand status took 6 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::getState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand status Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand status took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseState Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseTrackInfo Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"她说 (Live)","artist":"张碧晨","album":"中国好声音第三季 第2期","uri":"hdd1/张碧晨 - 她说 (Live)hires.flac","trackType":"flac"} Aug 23 16:14:25 volumio volumio[1213]: verbose: CURRENT POSITION 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState stateService play Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus stop Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 7ms Aug 23 16:14:25 volumio volumio[1213]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 16:14:25 volumio volumio[1213]: verbose: ControllerMpd::parseTrackInfo Aug 23 16:14:25 volumio volumio[1213]: info: ControllerMpd::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::servicePushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: verbose: STATE SERVICE {"status":"play","position":0,"seek":934,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"971 Kbps","isStreaming":false,"title":"她说 (Live)","artist":"张碧晨","album":"中国好声音第三季 第2期","uri":"hdd1/张碧晨 - 她说 (Live)hires.flac","trackType":"flac"} Aug 23 16:14:25 volumio volumio[1213]: verbose: CURRENT POSITION 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState stateService play Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::syncState currentStatus play Aug 23 16:14:25 volumio volumio[1213]: info: Received an update from plugin. extracting info from payload Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: CoreStateMachine::pushState Aug 23 16:14:25 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:25 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Aug 23 16:14:25 volumio volumio[1213]: info: ------------------------------ 6ms Aug 23 16:14:29 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Aug 23 16:14:29 volumio volumio[1213]: info: CorePlayQueue::getTrack 1 Aug 23 16:14:31 volumio volumio[1213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 16:14:31 volumio volumio[1213]: Error: connect ETIMEDOUT 67.228.235.91:80 Aug 23 16:14:31 volumio volumio[1213]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 23 16:14:31 volumio volumio[1213]: errno: -110, Aug 23 16:14:31 volumio volumio[1213]: code: 'ETIMEDOUT', Aug 23 16:14:31 volumio volumio[1213]: syscall: 'connect', Aug 23 16:14:31 volumio volumio[1213]: address: '67.228.235.91', Aug 23 16:14:31 volumio volumio[1213]: port: 80 Aug 23 16:14:31 volumio volumio[1213]: } Aug 23 16:14:31 volumio volumio[1213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 16:14:31 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 16:13 Aug 23 16:14:31 volumio sudo[1766]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"