-- Logs begin at Sat 2025-03-08 18:33:15 CST, end at Sat 2025-03-08 19:30:57 CST. -- Mar 08 19:29:05 volumio-intelhd sudo[12251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 19:29:05 volumio-intelhd sudo[12251]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:05 volumio-intelhd sudo[12251]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:05 volumio-intelhd sudo[12254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:29:05 volumio-intelhd sudo[12254]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:05 volumio-intelhd sudo[12254]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:05 volumio-intelhd volumio[8139]: verbose: New Socket.io Connection to 192.168.23.222 from 192.168.23.46 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: Received Get System Info Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: Discovery: Getting this device information Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: Listing playlists Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetQueue Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreStateMachine::getQueue Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getQueue Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: Received Get System Info Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: Discovery: Getting this device information Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:05 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:06 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:29:06 volumio-intelhd volumio[8139]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E6%A1%91/%E5%8F%97%E4%BA%86%E7%82%B9%E4%BC%A4/8d2bc4ec-4945-4800-bf2c-9627afb1cac6.jpg' Mar 08 19:29:08 volumio-intelhd sudo[12275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 19:29:08 volumio-intelhd sudo[12275]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:08 volumio-intelhd sudo[12275]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:08 volumio-intelhd sudo[12278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:29:08 volumio-intelhd sudo[12278]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:08 volumio-intelhd sudo[12278]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:08 volumio-intelhd volumio[8139]: verbose: New Socket.io Connection to 192.168.23.222 from 192.168.23.46 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: Received Get System Info Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: Discovery: Getting this device information Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: Listing playlists Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetQueue Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreStateMachine::getQueue Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getQueue Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: Received Get System Info Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: Discovery: Getting this device information Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetState Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:08 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:29:15 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 19:29:18 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Retrieving Cloud Streaming UI Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Getting Tidal Cloud Configuration Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Getting Qobuz Cloud Configuration Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Asking plugin for UI Config Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Getting Spotify Cloud Configuration Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Asking plugin for UI Config Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Saving Spotify Acccount Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Got Tidal Cloud Configuration Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Got it Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: Got it Mar 08 19:29:31 volumio-intelhd volumio[8139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:31 volumio-intelhd volumio[8139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 19:29:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 08 19:29:34 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Mar 08 19:29:50 volumio-intelhd sudo[12483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 19:29:50 volumio-intelhd sudo[12483]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:50 volumio-intelhd sudo[12483]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: MPD Permissions set Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd sudo[12486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 19:29:50 volumio-intelhd sudo[12486]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:50 volumio-intelhd systemd[1]: Stopping Music Player Daemon... Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 08 19:29:50 volumio-intelhd systemd[1]: mpd.service: Succeeded. Mar 08 19:29:50 volumio-intelhd systemd[1]: Stopped Music Player Daemon. Mar 08 19:29:50 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:29:50 volumio-intelhd systemd[1]: Starting Music Player Daemon... Mar 08 19:29:50 volumio-intelhd sudo[12494]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 08 19:29:50 volumio-intelhd sudo[12494]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:29:50 volumio-intelhd sudo[12494]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:50 volumio-intelhd mpd[12497]: Mar 08 19:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 19:29:50 volumio-intelhd systemd[1]: Started Music Player Daemon. Mar 08 19:29:50 volumio-intelhd volumio[8139]: error: Upnp client error: Error: This socket has been ended by the other party Mar 08 19:29:50 volumio-intelhd sudo[12486]: pam_unix(sudo:session): session closed for user root Mar 08 19:29:50 volumio-intelhd volumio[8139]: error: updateQueue error: null Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPlay Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::play index 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::stop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::updateTrackBlock Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrackBlock Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::serviceStop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::serviceStop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ControllerMpd::stop Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand stop took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::play index undefined Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICOS/阿桑/叶子(秋天版) - 阿桑.flac Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand stop took 0 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Mar 08 19:29:58 volumio-intelhd volumio[8139]: ---------------------------- MPD announces system playlist update Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Ignoring MPD Status Update Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand clear took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICOS/阿桑/叶子(秋天版) - 阿桑.flac" Mar 08 19:29:58 volumio-intelhd volumio[8139]: error: updateQueue error: null Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ------------------------------ 0ms Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Mar 08 19:29:58 volumio-intelhd volumio[8139]: ---------------------------- MPD announces system playlist update Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Ignoring MPD Status Update Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand add "USB/MUSICOS/阿桑/叶子(秋天版) - 阿桑.flac" took 0 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ------------------------------ 0ms Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand play took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Mar 08 19:29:58 volumio-intelhd volumio[8139]: ---------------------------- MPD announces state update: player Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ControllerMpd::getState Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Mar 08 19:29:58 volumio-intelhd volumio[8139]: ---------------------------- MPD announces state update: player Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ControllerMpd::getState Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseState Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseState Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ControllerMpd::pushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::servicePushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"叶子(秋天版)","artist":"阿桑","album":"受了点伤","uri":"USB/MUSICOS/阿桑/叶子(秋天版) - 阿桑.flac","trackType":"flac"} Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: CURRENT POSITION 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState stateService play Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ------------------------------ 2ms Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ControllerMpd::pushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::servicePushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"叶子(秋天版)","artist":"阿桑","album":"受了点伤","uri":"USB/MUSICOS/阿桑/叶子(秋天版) - 阿桑.flac","trackType":"flac"} Mar 08 19:29:58 volumio-intelhd volumio[8139]: verbose: CURRENT POSITION 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState stateService play Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: Received an update from plugin. extracting info from payload Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:29:58 volumio-intelhd volumio[8139]: info: ------------------------------ 4ms Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: CURURI: music-library/USB/MUSICOS/CD 054 - Piano Variations I Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preload queue cleared Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/02 - Sonata No.20 in G major Op.49,2 - I. Allegro non troppo.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/03 - Sonata No.20 in G major Op.49,2 - II. Tempo di menuetto.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/04 - 5 Variations on 'Rule Britannia' in D major WoO 79.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/05 - 7 Variations on 'God Save The King' in D major WoO 78.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/06 - 12 Variations on a Russian Dance from Wranitsky's Ballet 'Das Waldmadchen' in A major WoO 71.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/07 - 6 Variations on an Original Theme in G major Op.34.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Preloading song: music-library/USB/MUSICOS/CD 054 - Piano Variations I/08 - 6 Variations on a Theme from 'Ruins of Athens' in D major Op.76.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F01%20-%2015%20Variations%20on%20a%20theme%20from%20'Prometheus'%2C%20Op.35%20in%20E%20flat%20major%20'Eroica'.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/02 - Sonata No.20 in G major Op.49,2 - I. Allegro non troppo.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F02%20-%20Sonata%20No.20%20in%20G%20major%20Op.49%2C2%20-%20I.%20Allegro%20non%20troppo.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/02 - Sonata No.20 in G major Op.49,2 - I. Allegro non troppo.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/03 - Sonata No.20 in G major Op.49,2 - II. Tempo di menuetto.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F03%20-%20Sonata%20No.20%20in%20G%20major%20Op.49%2C2%20-%20II.%20Tempo%20di%20menuetto.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/03 - Sonata No.20 in G major Op.49,2 - II. Tempo di menuetto.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/04 - 5 Variations on 'Rule Britannia' in D major WoO 79.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F04%20-%205%20Variations%20on%20'Rule%20Britannia'%20in%20D%20major%20WoO%2079.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/04 - 5 Variations on 'Rule Britannia' in D major WoO 79.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/05 - 7 Variations on 'God Save The King' in D major WoO 78.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F05%20-%207%20Variations%20on%20'God%20Save%20The%20King'%20in%20D%20major%20WoO%2078.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/05 - 7 Variations on 'God Save The King' in D major WoO 78.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/06 - 12 Variations on a Russian Dance from Wranitsky's Ballet 'Das Waldmadchen' in A major WoO 71.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F06%20-%2012%20Variations%20on%20a%20Russian%20Dance%20from%20Wranitsky's%20Ballet%20'Das%20Waldmadchen'%20in%20A%20major%20WoO%2071.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/06 - 12 Variations on a Russian Dance from Wranitsky's Ballet 'Das Waldmadchen' in A major WoO 71.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/07 - 6 Variations on an Original Theme in G major Op.34.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F07%20-%206%20Variations%20on%20an%20Original%20Theme%20in%20G%20major%20Op.34.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/07 - 6 Variations on an Original Theme in G major Op.34.flac Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: Exploding uri music-library/USB/MUSICOS/CD 054 - Piano Variations I/08 - 6 Variations on a Theme from 'Ruins of Athens' in D major Op.76.flac in service mpd Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: ALBUMART /albumart?cacheid=166&web=Ludwig%20van%20Beethoven/Complete%20Works%20CD%20054%20-%20Piano%20Variations%20I/extralarge&path=%2Fmnt%2FUSB%2FMUSICOS%2FCD%20054%20-%20Piano%20Variations%20I%2F08%20-%206%20Variations%20on%20a%20Theme%20from%20'Ruins%20of%20Athens'%20in%20D%20major%20Op.76.flac&metadata=false Mar 08 19:30:31 volumio-intelhd volumio[8139]: info: URI /mnt/USB/MUSICOS/CD 054 - Piano Variations I/08 - 6 Variations on a Theme from 'Ruins of Athens' in D major Op.76.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Preload queue cleared Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::ClearQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::updateTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::serviceStop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 4 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::serviceStop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::clearPlayQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::saveQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::addQueueItems Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::addQueueItems Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Preload queue cleared Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::saveQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::updateTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPlay Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::play index 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::addQueueItems Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::addQueueItems Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Preload queue cleared Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/02 - Sonata No.20 in G major Op.49,2 - I. Allegro non troppo.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/02 - Sonata No.20 in G major Op.49,2 - I. Allegro non troppo.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/03 - Sonata No.20 in G major Op.49,2 - II. Tempo di menuetto.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/03 - Sonata No.20 in G major Op.49,2 - II. Tempo di menuetto.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/04 - 5 Variations on 'Rule Britannia' in D major WoO 79.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/04 - 5 Variations on 'Rule Britannia' in D major WoO 79.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/05 - 7 Variations on 'God Save The King' in D major WoO 78.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/05 - 7 Variations on 'God Save The King' in D major WoO 78.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/06 - 12 Variations on a Russian Dance from Wranitsky's Ballet 'Das Waldmadchen' in A major WoO 71.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/06 - 12 Variations on a Russian Dance from Wranitsky's Ballet 'Das Waldmadchen' in A major WoO 71.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/07 - 6 Variations on an Original Theme in G major Op.34.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/07 - 6 Variations on an Original Theme in G major Op.34.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Adding Item to queue: music-library/USB/MUSICOS/CD 054 - Piano Variations I/08 - 6 Variations on a Theme from 'Ruins of Athens' in D major Op.76.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Using cached record of: music-library/USB/MUSICOS/CD 054 - Piano Variations I/08 - 6 Variations on a Theme from 'Ruins of Athens' in D major Op.76.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::saveQueue Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::play index undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::updateTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrackBlock Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Mar 08 19:30:35 volumio-intelhd volumio[8139]: ---------------------------- MPD announces state update: player Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand stop took 4 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::getState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand stop took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Mar 08 19:30:35 volumio-intelhd volumio[8139]: ---------------------------- MPD announces system playlist update Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Ignoring MPD Status Update Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand status took 0 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand clear took 0 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac" Mar 08 19:30:35 volumio-intelhd volumio[8139]: error: updateQueue error: null Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ------------------------------ 1ms Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand add "USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac" took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:30:35 volumio-intelhd volumio[8139]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ------------------------------ 1ms Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Mar 08 19:30:35 volumio-intelhd volumio[8139]: ---------------------------- MPD announces system playlist update Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Ignoring MPD Status Update Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Mar 08 19:30:35 volumio-intelhd volumio[8139]: ---------------------------- MPD announces state update: player Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand play took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::getState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ------------------------------ 1ms Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Mar 08 19:30:35 volumio-intelhd volumio[8139]: ---------------------------- MPD announces state update: player Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::getState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand status took 0 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseState Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::pushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::servicePushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'","artist":"Ludwig van Beethoven","album":"Complete Works CD 054 - Piano Variations I","uri":"USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac","trackType":"flac"} Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: CURRENT POSITION 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState stateService play Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ------------------------------ 1ms Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ControllerMpd::pushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::servicePushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'","artist":"Ludwig van Beethoven","album":"Complete Works CD 054 - Piano Variations I","uri":"USB/MUSICOS/CD 054 - Piano Variations I/01 - 15 Variations on a theme from 'Prometheus', Op.35 in E flat major 'Eroica'.flac","trackType":"flac"} Mar 08 19:30:35 volumio-intelhd volumio[8139]: verbose: CURRENT POSITION 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState stateService play Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: Received an update from plugin. extracting info from payload Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreStateMachine::pushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CorePlayQueue::getTrack 0 Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: CoreCommandRouter::volumioPushState Mar 08 19:30:35 volumio-intelhd volumio[8139]: info: ------------------------------ 3ms Mar 08 19:30:36 volumio-intelhd volumio[8139]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E6%A1%91/%E5%8F%97%E4%BA%86%E7%82%B9%E4%BC%A4/8d2bc4ec-4945-4800-bf2c-9627afb1cac6.jpg' Mar 08 19:30:42 volumio-intelhd ntpd[11298]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Mar 08 19:30:57 volumio-intelhd volumio[8139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:30:57 volumio-intelhd volumio[8139]: Error: connect ETIMEDOUT 108.160.170.43:443 Mar 08 19:30:57 volumio-intelhd volumio[8139]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 08 19:30:57 volumio-intelhd volumio[8139]: errno: -110, Mar 08 19:30:57 volumio-intelhd volumio[8139]: code: 'ETIMEDOUT', Mar 08 19:30:57 volumio-intelhd volumio[8139]: syscall: 'connect', Mar 08 19:30:57 volumio-intelhd volumio[8139]: address: '108.160.170.43', Mar 08 19:30:57 volumio-intelhd volumio[8139]: port: 443 Mar 08 19:30:57 volumio-intelhd volumio[8139]: } Mar 08 19:30:57 volumio-intelhd volumio[8139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:30:57 volumio-intelhd sudo[12790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 19:29 Mar 08 19:30:57 volumio-intelhd sudo[12790]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"