Dec 27 17:33:05 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:05 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:05 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:05 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:05 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:05 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:05 volumio4 welcome[8360]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:05 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:05 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:05 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:05 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:05 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:05 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:05 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:05 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:05 volumio4 welcome[8376]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:05 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:05 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:09 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87:3000 from 192.168.2.56 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 27 17:33:09 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:09 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:09 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:09 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:09 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:09 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:10 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:10 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:10 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87:3000 from 192.168.2.56 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 17:33:10 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 17:33:11 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:11 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:11 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:11 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:11 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 27 17:33:12 volumio4 sudo[8420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 27 17:33:12 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:12 volumio4 sudo[8420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:12 volumio4 sudo[8420]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:12 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:12 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:12 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:12 volumio4 sudo[8422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 27 17:33:12 volumio4 sudo[8422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 sudo[8422]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 welcome[8430]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:12 volumio4 sudo[8455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 17:33:12 volumio4 sudo[8455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 sudo[8446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 27 17:33:12 volumio4 sudo[8442]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 27 17:33:12 volumio4 sudo[8455]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 sudo[8462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 17:33:12 volumio4 sudo[8446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:12 volumio4 sudo[8442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:12 volumio4 sudo[8462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:12 volumio4 sudo[8446]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 sudo[8442]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 sudo[8462]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:12 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:12 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:12 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:12 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:12 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:12 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:12 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:12 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:12 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:12 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:12 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:12 volumio4 welcome[8491]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 17:33:12 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:12 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:12 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87:3000 from 192.168.2.56 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 17:33:12 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 17:33:14 volumio4 sudo[8511]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 17:33:14 volumio4 sudo[8509]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 17:33:14 volumio4 sudo[8509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:14 volumio4 sudo[8511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:14 volumio4 sudo[8509]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:14 volumio4 sudo[8511]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:14 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87 from 192.168.2.56 UA: Mozilla/5.0 (Linux; Android 12; LIO-AN00 Build/HUAWEILIO-AN00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 27 17:33:14 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:14 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:14 volumio4 sudo[8525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 17:33:14 volumio4 sudo[8525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:14 volumio4 sudo[8525]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:14 volumio4 sudo[8527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 17:33:14 volumio4 sudo[8527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 17:33:14 volumio4 sudo[8527]: pam_unix(sudo:session): session closed for user root Dec 27 17:33:14 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87 from 192.168.2.56 UA: Mozilla/5.0 (Linux; Android 12; LIO-AN00 Build/HUAWEILIO-AN00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:15 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 17:33:15 volumio4 volumio[1076]: info: Received Get System Info Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:15 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:15 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:15 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:15 volumio4 volumio[1076]: info: Listing playlists Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 17:33:15 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 17:33:17 volumio4 volumio[1076]: info: Received Get System Info Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:17 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:17 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:17 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:17 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:17 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87:3000 from 192.168.2.56 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 17:33:17 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioNext Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::next Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::stop Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::updateTrackBlock Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrackBlock Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::serviceStop Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::serviceStop Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::stop Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand stop took 45 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::play index undefined Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::startPlaybackTimer Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::updateTrackBlock Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrackBlock Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 10 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand stop took 9 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 8 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 8 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 8 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand clear took 2 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape" Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: 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":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService stop Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: No code Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:33:19 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:33:19 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 80ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 79ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 78ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 78ms Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: error: updateQueue error: null Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 75ms Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand add "NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape" took 74 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 6ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 4ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 3ms Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand play Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:33:19 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 22ms Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand play took 20 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 19ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 17ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 17ms Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 9 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 9 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: Dec 27 17:33:19 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 20 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 17 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 16 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 10 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 10 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 43ms Dec 27 17:33:19 volumio4 volumio[1076]: info: ------------------------------ 73ms Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand status took 59 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:19 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:19 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:19 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:19 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:20 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:20 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:20 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:20 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:20 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 333ms Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 326ms Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 326ms Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 322ms Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 318ms Dec 27 17:33:20 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:20 volumio4 welcome[8648]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:20 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:20 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:20 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 316 milliseconds Dec 27 17:33:20 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:33:20 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:33:20 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:20 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":889,"duration":352,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:33:20 volumio4 volumio[1076]: verbose: CURRENT POSITION 1 Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:33:20 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:20 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:33:20 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:33:20 volumio4 volumio[1076]: info: ------------------------------ 405ms Dec 27 17:33:20 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:20 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:20 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:20 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:20 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 17:33:20 volumio4 volumio[1076]: info: Discovery: Getting this device information Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:20 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 17:33:20 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:20 volumio4 welcome[8666]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:20 volumio4 volumio[1076]: verbose: New Socket.io Connection to 192.168.2.87:3000 from 192.168.2.56 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 17:33:20 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:20 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 17:33:20 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:22 volumio4 volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 17:33:22 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:22 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:22 volumio4 volumio[1076]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 27 17:33:22 volumio4 volumio[1076]: info: Retrying Login Due to Network Error Dec 27 17:33:24 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 17:33:28 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:28 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:28 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:28 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:28 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:28 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:28 volumio4 welcome[8727]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:28 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:28 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:28 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:28 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:28 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:28 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:28 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:28 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:28 volumio4 welcome[8742]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:28 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:28 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:29 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:29 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:29 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 17:33:30 volumio4 volumio[1076]: info: CoreCommandRouter::Close All Modals sent Dec 27 17:33:32 volumio4 ntpd[1413]: PROTO: 2606:4700:f1::123 unlink local addr 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 -> Dec 27 17:33:32 volumio4 volumio[1076]: info: MyVolumio login type: Token Dec 27 17:33:32 volumio4 volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 17:33:32 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 17:33:34 volumio4 volumio[1076]: (node:1076) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 27 17:33:35 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:35 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:35 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:35 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:35 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:35 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:35 volumio4 welcome[8804]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:35 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:35 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:35 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:35 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:35 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:35 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:35 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:35 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:35 volumio4 welcome[8817]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:35 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:35 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:44 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:44 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:44 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:44 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:44 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:44 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:44 volumio4 welcome[8876]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:44 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:44 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:44 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:44 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:44 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:44 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:44 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:44 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:44 volumio4 welcome[8892]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:44 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:44 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:44 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:44 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:33:51 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:51 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:51 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:51 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:51 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:51 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:51 volumio4 welcome[8954]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:51 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:51 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:51 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:51 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:33:51 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:33:51 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:33:51 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:33:51 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:33:51 volumio4 welcome[8963]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:33:51 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:33:51 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:33:55 volumio4 ntpd[1413]: PROTO: 193.182.111.12 unlink local addr 192.168.2.87 -> Dec 27 17:33:59 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:33:59 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:34:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:34:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:34:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:34:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:34:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:34:00 volumio4 welcome[9012]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:34:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:34:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:34:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:34:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:34:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:34:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:34:00 volumio4 welcome[9031]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:34:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:34:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:00 volumio4 volumio[1076]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Dec 27 17:34:00 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Dec 27 17:34:00 volumio4 volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 17:34:00 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 17:34:02 volumio4 volumio[1076]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 27 17:34:02 volumio4 volumio[1076]: info: Retrying Login Due to Network Error Dec 27 17:34:05 volumio4 ntpd[1413]: PROTO: 193.182.111.142 unlink local addr 192.168.2.87 -> Dec 27 17:34:07 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:07 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:34:07 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:34:07 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:34:07 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:34:07 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:34:07 volumio4 welcome[9084]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:34:07 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:34:07 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:07 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:07 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 27 17:34:07 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 27 17:34:07 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 27 17:34:07 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 27 17:34:07 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 27 17:34:07 volumio4 welcome[9102]: Resolved ip:[3] 192.168.2.87 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 27 17:34:07 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 27 17:34:07 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioNext Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::next Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::stop Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::updateTrackBlock Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrackBlock Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::serviceStop Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 1 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::serviceStop Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::stop Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand stop took 33 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::play index undefined Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::startPlaybackTimer Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::updateTrackBlock Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrackBlock Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand stop took 1 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 1 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 1 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand clear took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape" Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: 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":"Towards a New Life, Op.35c","artist":"Suk, Josef","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape","trackType":"ape"} Dec 27 17:34:07 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService stop Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: No code Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:34:07 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:34:07 volumio4 volumio[1076]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 49ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 47ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 48ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 48ms Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: error: updateQueue error: null Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 46ms Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand add "NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape" took 45 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand play Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 18ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 21ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 21ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 22ms Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand play took 21 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces system playlist update Dec 27 17:34:07 volumio4 volumio[1076]: info: Ignoring MPD Status Update Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 4ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 9ms Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 9ms Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 2 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 3 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 3 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: Dec 27 17:34:07 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 4 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 4 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 5 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: info: sendMpdCommand status took 6 milliseconds Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:07 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:07 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:07 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:07 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:07 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:07 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:07 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:07 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:07 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:07 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:07 volumio4 volumio[1076]: info: ------------------------------ 93ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 164ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 164ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 164ms Dec 27 17:34:08 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 193 milliseconds Dec 27 17:34:08 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 195 milliseconds Dec 27 17:34:08 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 195 milliseconds Dec 27 17:34:08 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 195 milliseconds Dec 27 17:34:08 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:08 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:08 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:08 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:08 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:08 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:08 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:08 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:08 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:08 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:08 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:08 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService play Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus play Dec 27 17:34:08 volumio4 volumio[1076]: info: Received an update from plugin. extracting info from payload Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:08 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:08 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 296ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 305ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 304ms Dec 27 17:34:08 volumio4 volumio[1076]: info: ------------------------------ 304ms Dec 27 17:34:10 volumio4 volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 17:34:10 volumio4 volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 27 17:34:10 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::servicePause Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::servicePause Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::pause Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand pause Dec 27 17:34:11 volumio4 volumio[1076]: info: Dec 27 17:34:11 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand pause took 3 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:11 volumio4 volumio[1076]: info: Dec 27 17:34:11 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:11 volumio4 volumio[1076]: info: Dec 27 17:34:11 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:11 volumio4 volumio[1076]: info: Dec 27 17:34:11 volumio4 volumio[1076]: ---------------------------- MPD announces state update: player Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::getState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand status took 4 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand status took 3 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand status took 2 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand status took 1 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseState Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3878,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:11 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:11 volumio4 volumio[1076]: info: ------------------------------ 17ms Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:11 volumio4 volumio[1076]: verbose: ControllerMpd::parseTrackInfo Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3878,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:11 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3878,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:11 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:11 volumio4 volumio[1076]: info: ControllerMpd::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::servicePushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3878,"duration":385,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pomp and Circumstance March No. 1","artist":"Elgar, Sir Edward","album":"Pomp & Pizazz","uri":"NAS/MusicBox/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape","trackType":"ape"} Dec 27 17:34:11 volumio4 volumio[1076]: verbose: CURRENT POSITION 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState stateService pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::syncState currentStatus pause Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::pushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CorePlayQueue::getTrack 2 Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 27 17:34:11 volumio4 volumio[1076]: info: CoreStateMachine::stPlaybackTimer Dec 27 17:34:11 volumio4 volumio[1076]: info: ------------------------------ 47ms Dec 27 17:34:11 volumio4 volumio[1076]: info: ------------------------------ 46ms Dec 27 17:34:11 volumio4 volumio[1076]: info: ------------------------------ 46ms Dec 27 17:34:11 volumio4 volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 17:34:11 volumio4 volumio[1076]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 27 17:34:11 volumio4 volumio[1076]: at Object.readdirSync (node:fs:1543:3) Dec 27 17:34:11 volumio4 volumio[1076]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 27 17:34:11 volumio4 volumio[1076]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 27 17:34:11 volumio4 volumio[1076]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 27 17:34:11 volumio4 volumio[1076]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 27 17:34:11 volumio4 volumio[1076]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 27 17:34:11 volumio4 volumio[1076]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 27 17:34:11 volumio4 volumio[1076]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 27 17:34:11 volumio4 volumio[1076]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 27 17:34:11 volumio4 volumio[1076]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 27 17:34:11 volumio4 volumio[1076]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 27 17:34:11 volumio4 volumio[1076]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 27 17:34:11 volumio4 volumio[1076]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 27 17:34:11 volumio4 volumio[1076]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 27 17:34:11 volumio4 volumio[1076]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 27 17:34:11 volumio4 volumio[1076]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 27 17:34:11 volumio4 volumio[1076]: errno: -20, Dec 27 17:34:11 volumio4 volumio[1076]: syscall: 'scandir', Dec 27 17:34:11 volumio4 volumio[1076]: code: 'ENOTDIR', Dec 27 17:34:11 volumio4 volumio[1076]: path: '/tmp/peppyrunning' Dec 27 17:34:11 volumio4 volumio[1076]: } Dec 27 17:34:11 volumio4 volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 17:34:11 volumio4 sudo[9253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 17:33' Dec 27 17:34:11 volumio4 sudo[9253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"