-- Logs begin at Wed 2024-10-09 17:05:00 CST, end at Wed 2024-10-09 17:07:39 CST. -- Oct 09 17:06:00 volumio volumio[1590]: ...............................................................................................................................................................................................................................................++++ Oct 09 17:06:03 volumio volumio[1058]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 09 17:06:03 volumio volumio[1058]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 09 17:06:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 17:06:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 17:06:03 volumio volumio[1058]: info: Starting MyVolumio Remote Streaming Endpoints Oct 09 17:06:03 volumio volumio[1058]: info: MyVolumio login type: Token Oct 09 17:06:03 volumio volumio[1058]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 09 17:06:03 volumio volumio[1058]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 09 17:06:04 volumio volumio[1590]: ..................................................................++++ Oct 09 17:06:04 volumio volumio[1590]: e is 65537 (0x010001) Oct 09 17:06:04 volumio volumio[1590]: writing RSA key Oct 09 17:06:04 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Oct 09 17:06:04 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 09 17:06:04 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 09 17:06:05 volumio volumio[1058]: info: Starting Streaming Service Transparent Proxy Oct 09 17:06:05 volumio volumio[1058]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 09 17:06:05 volumio volumio[1058]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 09 17:06:05 volumio volumio[1058]: info: Streaming services startup Oct 09 17:06:05 volumio volumio[1058]: info: Starting Streaming Daemon Oct 09 17:06:05 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 09 17:06:05 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 17:06:05 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Oct 09 17:06:05 volumio volumio[1058]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 09 17:06:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 09 17:06:05 volumio volumio[1058]: error: Cannot start Volumio Streaming Daemon Oct 09 17:06:05 volumio volumio[1058]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 09 17:06:05 volumio volumio[1058]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 09 17:06:05 volumio volumio[1058]: info: Cannot mount NAS 共享文件 at system boot, trial number 3 ,retrying in 5 seconds Oct 09 17:06:05 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 09 17:06:05 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:05 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:06 volumio volumio[1058]: STREAMING PROXY: Starting server on port 3245 Oct 09 17:06:06 volumio volumio[1058]: Node JS runtime: 14 Oct 09 17:06:10 volumio sudo[1905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=13818269189,password=Luys2468,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.75/共享文件 /mnt/NAS/共享文件 Oct 09 17:06:10 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 17:06:10 volumio kernel: CIFS: Attempting to mount //192.168.3.75/共享文件 Oct 09 17:06:13 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 17:06:13 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 17:06:13 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 09 17:06:13 volumio volumio-remote-updater[669]: No test mode Oct 09 17:06:13 volumio volumio-remote-updater[669]: No alpha test mode Oct 09 17:06:13 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 09 17:06:17 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 09 17:06:17 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 09 17:06:17 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root Oct 09 17:06:17 volumio volumio[1058]: info: Cannot mount NAS 共享文件 at system boot, trial number 4 ,retrying in 5 seconds Oct 09 17:06:17 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 17:06:18 volumio volumio[1058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 09 17:06:18 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:19 volumio volumio[1058]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

NEW ADDITIONS

 

 

","title":"Update v3.757","updateavailable":true} Oct 09 17:06:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 09 17:06:22 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=13818269189,password=Luys2468,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.75/共享文件 /mnt/NAS/共享文件 Oct 09 17:06:22 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 17:06:22 volumio kernel: CIFS: Attempting to mount //192.168.3.75/共享文件 Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:06:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 17:06:25 volumio volumio[1058]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 09 17:06:25 volumio volumio[1058]: info: Completed starting MyVolumio Plugin Oct 09 17:06:25 volumio volumio[1058]: info: BOOT COMPLETED Oct 09 17:06:25 volumio volumio[1058]: [Metrics] CommandRouter: 59s 247.19ms Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::volumiosetStartupVolume Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 17:06:25 volumio volumio[1058]: info: VolumeController:: Setting startup Volume 10 Oct 09 17:06:25 volumio volumio[1058]: info: VolumeController::SetAlsaVolume10 Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:06:25 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:06:25 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:06:25 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:06:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 09 17:06:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 09 17:06:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 09 17:06:28 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:28 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:28 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Oct 09 17:06:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 09 17:06:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 09 17:06:28 volumio volumio[1058]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 09 17:06:34 volumio ntpd[1053]: Soliciting pool server 162.159.200.1 Oct 09 17:06:35 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 09 17:06:35 volumio volumio[1058]: info: Retrying Login Due to Network Error Oct 09 17:06:37 volumio ntpd[1053]: Soliciting pool server 111.230.189.174 Oct 09 17:06:37 volumio ntpd[1053]: Soliciting pool server 202.112.31.197 Oct 09 17:06:37 volumio ntpd[1053]: Soliciting pool server 202.118.1.81 Oct 09 17:06:38 volumio ntpd[1053]: Soliciting pool server 119.28.206.193 Oct 09 17:06:38 volumio ntpd[1053]: Soliciting pool server 202.118.1.130 Oct 09 17:06:39 volumio ntpd[1053]: Soliciting pool server 2a03:8600::dd Oct 09 17:06:39 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:39 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:43 volumio ntpd[1053]: Soliciting pool server 193.182.111.14 Oct 09 17:06:43 volumio ntpd[1053]: Soliciting pool server 219.216.128.25 Oct 09 17:06:45 volumio volumio[1058]: info: MyVolumio login type: Token Oct 09 17:06:50 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:06:50 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:06:58 volumio volumio[1058]: info: Retrieving Cloud Streaming UI Oct 09 17:06:58 volumio volumio[1058]: info: Getting Tidal Cloud Configuration Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:06:58 volumio volumio[1058]: info: Getting Qobuz Cloud Configuration Oct 09 17:06:58 volumio volumio[1058]: info: Asking plugin for UI Config Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:06:58 volumio volumio[1058]: info: Getting Spotify Cloud Configuration Oct 09 17:06:58 volumio volumio[1058]: info: Asking plugin for UI Config Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:06:58 volumio volumio[1058]: info: Saving Spotify Acccount Oct 09 17:06:58 volumio volumio[1058]: info: Got Tidal Cloud Configuration Oct 09 17:06:58 volumio volumio[1058]: info: Got it Oct 09 17:06:58 volumio volumio[1058]: info: Got it Oct 09 17:06:58 volumio volumio[1058]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:06:58 volumio volumio[1058]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:06:58 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:07:00 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:00 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 09 17:07:01 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 17:07:01 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 17:07:01 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Oct 09 17:07:01 volumio sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 17:07:01 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 17:07:01 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root Oct 09 17:07:01 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.3.77 from 192.168.3.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 09 17:07:01 volumio volumio[1058]: info: Received Get System Info Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 09 17:07:01 volumio volumio[1058]: info: Discovery: Getting this device information Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:01 volumio volumio[1058]: info: Listing playlists Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 09 17:07:01 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:02 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:02 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 17:07:03 volumio volumio[1058]: info: Received Get System Info Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 09 17:07:03 volumio volumio[1058]: info: Discovery: Getting this device information Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:03 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 09 17:07:03 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: info: Retrieving Cloud Streaming UI Oct 09 17:07:04 volumio volumio[1058]: info: Getting Tidal Cloud Configuration Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: info: Getting Qobuz Cloud Configuration Oct 09 17:07:04 volumio volumio[1058]: info: Asking plugin for UI Config Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: info: Getting Spotify Cloud Configuration Oct 09 17:07:04 volumio volumio[1058]: info: Asking plugin for UI Config Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: info: Saving Spotify Acccount Oct 09 17:07:04 volumio volumio[1058]: info: Got Tidal Cloud Configuration Oct 09 17:07:04 volumio volumio[1058]: info: Got it Oct 09 17:07:04 volumio volumio[1058]: info: Got it Oct 09 17:07:04 volumio volumio[1058]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 17:07:04 volumio volumio[1058]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 09 17:07:07 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 09 17:07:08 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 09 17:07:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 09 17:07:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 09 17:07:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:12 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 09 17:07:12 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:12 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:12 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:15 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 09 17:07:15 volumio volumio[1058]: info: Retrying Login Due to Network Error Oct 09 17:07:15 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand rescan Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: info: Oct 09 17:07:16 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand rescan took 16 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: info: Oct 09 17:07:16 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:16 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: info: Oct 09 17:07:16 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:16 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: info: Oct 09 17:07:16 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:16 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 22 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 16 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 14 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 12 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 12 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Oct 09 17:07:16 volumio volumio[1058]: info: sendMpdCommand status took 7 milliseconds Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:16 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:16 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:16 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:16 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:16 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:16 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:16 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:16 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:16 volumio volumio[1058]: info: No code Oct 09 17:07:16 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:16 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ------------------------------ 627ms Oct 09 17:07:17 volumio volumio[1058]: info: ------------------------------ 621ms Oct 09 17:07:17 volumio volumio[1058]: info: ------------------------------ 619ms Oct 09 17:07:17 volumio volumio[1058]: info: ------------------------------ 618ms Oct 09 17:07:17 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:17 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: Oct 09 17:07:17 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 113 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 113 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 114 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 41 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 39 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 37 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 33 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 33 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 33 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 31 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 26 milliseconds Oct 09 17:07:17 volumio volumio[1058]: info: sendMpdCommand status took 24 milliseconds Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:17 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:17 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 09 17:07:17 volumio systemd[1]: Reached target Host and Network Name Lookups. Oct 09 17:07:17 volumio systemd[1]: Starting Preprocess NFS configuration... Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio systemd[1]: nfs-config.service: Succeeded. Oct 09 17:07:17 volumio systemd[1]: Started Preprocess NFS configuration. Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio systemd[1]: Starting Notify NFS peers of a restart... Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio sm-notify[2075]: Version 1.3.3 starting Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio systemd[1]: Started Notify NFS peers of a restart. Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio rpc.statd[2076]: Version 1.3.3 starting Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio rpc.statd[2076]: Flags: TI-RPC Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:17 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:17 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:17 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:17 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:17 volumio volumio[1058]: info: No code Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1233ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1234ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1162ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1162ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1155ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1155ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1155ms Oct 09 17:07:18 volumio volumio[1058]: info: ------------------------------ 1156ms Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand rescan Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: info: Oct 09 17:07:18 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand rescan took 10 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: info: Oct 09 17:07:18 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: info: Oct 09 17:07:18 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: info: Oct 09 17:07:18 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 25 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 23 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 20 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 19 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 18 milliseconds Oct 09 17:07:18 volumio volumio[1058]: info: sendMpdCommand status took 15 milliseconds Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:18 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:18 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: No code Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:18 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: No code Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:18 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:18 volumio volumio[1058]: info: No code Oct 09 17:07:18 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:18 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 498ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 491ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 490ms Oct 09 17:07:19 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 487 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 485 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 627ms Oct 09 17:07:19 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: Oct 09 17:07:19 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 221 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 219 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 218 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 30 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 28 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 27 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 24 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 22 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 21 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 18 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 16 milliseconds Oct 09 17:07:19 volumio volumio[1058]: info: sendMpdCommand status took 16 milliseconds Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:19 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: No code Oct 09 17:07:19 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:19 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 876ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 876ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 686ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 686ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 681ms Oct 09 17:07:19 volumio volumio[1058]: info: ------------------------------ 681ms Oct 09 17:07:20 volumio volumio[1058]: info: ------------------------------ 676ms Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand rescan Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand rescan took 2 milliseconds Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 23 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 17 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 16 milliseconds Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:20 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: No code Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:20 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: No code Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: ------------------------------ 295ms Oct 09 17:07:20 volumio volumio[1058]: info: ------------------------------ 290ms Oct 09 17:07:20 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 291 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 290 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 289 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 283 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:20 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: No code Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:20 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: No code Oct 09 17:07:20 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:20 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:20 volumio volumio[1058]: info: ------------------------------ 617ms Oct 09 17:07:20 volumio volumio[1058]: info: ------------------------------ 615ms Oct 09 17:07:20 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: MPD Database updated - AlbumList cache refreshed Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: database Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: Oct 09 17:07:20 volumio volumio[1058]: ---------------------------- MPD announces state update: update Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: ControllerMpd::getState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 419 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 415 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 413 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 86 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 85 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 80 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 78 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 77 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 74 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 68 milliseconds Oct 09 17:07:20 volumio volumio[1058]: info: sendMpdCommand status took 67 milliseconds Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:20 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:20 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:21 volumio volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 09 17:07:21 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ControllerMpd::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 09 17:07:21 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: No code Oct 09 17:07:21 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 09 17:07:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 1098ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 1099ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 767ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 767ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 764ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 762ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 757ms Oct 09 17:07:21 volumio volumio[1058]: info: ------------------------------ 758ms Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:24 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:24 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:24 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:25 volumio volumio[1058]: info: MyVolumio login type: Token Oct 09 17:07:27 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:28 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:31 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:34 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Oct 09 17:07:34 volumio volumio[1058]: info: Cannot mount NAS 共享文件 at system boot, trial number 1 ,retrying in 5 seconds Oct 09 17:07:35 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 09 17:07:35 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 09 17:07:35 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:36 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 09 17:07:39 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 17:07:39 volumio volumio[1058]: TypeError: Cannot read property 'replace' of undefined Oct 09 17:07:39 volumio volumio[1058]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 09 17:07:39 volumio volumio[1058]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 09 17:07:39 volumio volumio[1058]: at listOnTimeout (internal/timers.js:557:17) Oct 09 17:07:39 volumio volumio[1058]: at processTimers (internal/timers.js:500:7) Oct 09 17:07:39 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 17:07:39 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 17:06 Oct 09 17:07:39 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"