-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Mon 2025-03-17 10:16:51 JST. -- Mar 17 10:15:02 volumio volumio[1096]: info: Getting Spotify volume Mar 17 10:15:02 volumio volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 17 10:15:02 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:02 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:02 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:15:02 volumio volumio[1096]: SPOTIFY: SPOTIFY VOLUME undefined Mar 17 10:15:02 volumio volumio[1096]: SPOTIFY: VOLUMIO VOLUME 100 Mar 17 10:15:02 volumio volumio[1096]: info: Aligning Spotify Volume to Volumio Volume Mar 17 10:15:02 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:02 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:02 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 100 Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin bluetooth to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin multiroom to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin metavolumio to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin cd_controller to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 17 10:15:02 volumio volumio[1096]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 17 10:15:03 volumio volumio[1096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 17 10:15:03 volumio volumio[1096]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 17 10:15:03 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:03 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:03 volumio volumio[1096]: info: Starting MyVolumio Remote Streaming Endpoints Mar 17 10:15:03 volumio volumio[1096]: info: MyVolumio login type: Token Mar 17 10:15:03 volumio volumio[1096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 17 10:15:03 volumio volumio[1096]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 17 10:15:03 volumio ntpd[811]: Soliciting pool server 129.250.35.250 Mar 17 10:15:03 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:03 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:03 volumio volumio[1096]: info: Starting Streaming Service Transparent Proxy Mar 17 10:15:04 volumio volumio[1096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 17 10:15:04 volumio volumio[1096]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 17 10:15:04 volumio volumio[1096]: info: Streaming services startup Mar 17 10:15:04 volumio volumio[1096]: info: Starting Streaming Daemon Mar 17 10:15:04 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 17 10:15:04 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:15:04 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:04 volumio volumio[1096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 17 10:15:04 volumio volumio[1096]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 17 10:15:04 volumio volumio[1096]: info: Sending Spotify command with payload to local API: /player/volume Mar 17 10:15:04 volumio volumio[1096]: error: Cannot start Volumio Streaming Daemon Mar 17 10:15:04 volumio volumio[1096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 17 10:15:04 volumio volumio[1096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 17 10:15:04 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 1 ,retrying in 5 seconds Mar 17 10:15:04 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 1 ,retrying in 5 seconds Mar 17 10:15:04 volumio ntpd[811]: Soliciting pool server 129.250.35.251 Mar 17 10:15:04 volumio volumio[1096]: error: MyVolumio Custom Token format not valid, refreshing it Mar 17 10:15:04 volumio volumio[1096]: STREAMING PROXY: Starting server on port 3245 Mar 17 10:15:04 volumio volumio[1096]: Node JS runtime: 14 Mar 17 10:15:04 volumio ntpd[811]: Soliciting pool server 202.181.103.212 Mar 17 10:15:05 volumio ntpd[811]: Soliciting pool server 133.243.238.163 Mar 17 10:15:05 volumio ntpd[811]: Soliciting pool server 139.162.81.45 Mar 17 10:15:05 volumio ntpd[811]: Soliciting pool server 162.159.200.1 Mar 17 10:15:05 volumio ntpd[811]: Soliciting pool server 139.162.81.45 Mar 17 10:15:05 volumio volumio[1096]: info: MyVolumio login type: Token Mar 17 10:15:05 volumio ntpd[811]: Soliciting pool server 133.243.238.243 Mar 17 10:15:06 volumio volumio[1096]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 17 10:15:06 volumio ntpd[811]: Soliciting pool server 167.179.119.205 Mar 17 10:15:06 volumio ntpd[811]: Soliciting pool server 122.215.240.52 Mar 17 10:15:06 volumio ntpd[811]: Soliciting pool server 133.130.121.141 Mar 17 10:15:06 volumio volumio[1096]: info: MyVolumio token set successfully Mar 17 10:15:06 volumio volumio[1096]: info: MYVOLUMIO: Adding device Mar 17 10:15:06 volumio volumio[1096]: info: MYVOLUMIO: Evaluating Server Mar 17 10:15:07 volumio volumio[1096]: info: MyVolumio status changed Mar 17 10:15:07 volumio volumio[1096]: info: Streaming services startup Mar 17 10:15:07 volumio volumio[1096]: info: Starting Streaming Daemon Mar 17 10:15:07 volumio volumio[1096]: info: Removing browser output: myVolumio user plan is not superstar Mar 17 10:15:07 volumio volumio[1096]: info: Removing audio output: Mar 17 10:15:07 volumio volumio[1096]: info: Stoppping Tunnel 1 Mar 17 10:15:07 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 17 10:15:07 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:15:07 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 17 10:15:07 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:07 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:15:07 volumio volumio[1096]: error: Cannot start Volumio Streaming Daemon Mar 17 10:15:07 volumio volumio[1096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 17 10:15:07 volumio volumio[1096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 17 10:15:07 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:07 volumio volumio[1096]: info: Remote SSH Stopped Mar 17 10:15:07 volumio ntpd[811]: Soliciting pool server 23.81.40.154 Mar 17 10:15:07 volumio ntpd[811]: Soliciting pool server 45.76.211.39 Mar 17 10:15:07 volumio ntpd[811]: Soliciting pool server 23.81.40.154 Mar 17 10:15:08 volumio ntpd[811]: Soliciting pool server 2001:418:3ff::53 Mar 17 10:15:09 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.9:/Share/Import_CD /mnt/NAS/N1-051E Mar 17 10:15:09 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:15:09 volumio sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.9:/Share /mnt/NAS/N1-051E Mar 17 10:15:09 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:15:12 volumio ntpd[811]: receive: Unexpected origin timestamp 0xeb81f61f.620664d9 does not match aorg 0000000000.00000000 from server@129.250.35.250 xmt 0xeb81f620.13414f8f Mar 17 10:15:13 volumio volumio[1096]: info: Setting Geolocation for MyVolumio to as1 Mar 17 10:15:13 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:14 volumio volumio[1096]: info: Updating MyVolumio device info Mar 17 10:15:14 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:14 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:14 volumio volumio[1096]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 17 10:15:15 volumio volumio[1096]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 17 10:15:18 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 17 10:15:18 volumio volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 17 10:15:18 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:18 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:18 volumio volumio[1096]: info: MYVOLUMIO: Adding device Mar 17 10:15:18 volumio volumio[1096]: info: MYVOLUMIO: Evaluating Server Mar 17 10:15:19 volumio volumio[1096]: info: Setting Geolocation for MyVolumio to as1 Mar 17 10:15:19 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:19 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:19 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:20 volumio volumio[1096]: info: Updating MyVolumio device info Mar 17 10:15:20 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:20 volumio volumio[1096]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 17 10:15:21 volumio volumio[1096]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:15:23 volumio volumio-remote-updater[612]: No test mode Mar 17 10:15:23 volumio volumio-remote-updater[612]: No alpha test mode Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 10:15:23 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 10:15:24 volumio volumio[1096]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 17 10:15:24 volumio volumio[1096]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 17 10:15:24 volumio volumio[1096]: info: Completed starting MyVolumio Plugin Mar 17 10:15:24 volumio volumio[1096]: [Metrics] CommandRouter: 67s 655.81ms Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::volumiosetStartupVolume Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:15:24 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:15:25 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 17 10:15:25 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 10:15:25 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 17 10:15:31 volumio volumio[1096]: info: BOOT COMPLETED Mar 17 10:15:54 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:54 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Mar 17 10:15:54 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 2 ,retrying in 5 seconds Mar 17 10:15:54 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 2 ,retrying in 5 seconds Mar 17 10:15:59 volumio volumio[1096]: verbose: New Socket.io Connection to 192.168.0.117 from 192.168.0.41 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Listing playlists Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Listing playlists Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Listing playlists Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Listing playlists Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: Received Get System Info Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:15:59 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:15:59 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:15:59 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:15:59 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Listing playlists Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Listing playlists Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Listing playlists Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: Received Get System Info Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 10:16:00 volumio volumio[1096]: info: Discovery: Getting this device information Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::volumioGetQueue Mar 17 10:16:00 volumio volumio[1096]: info: CoreStateMachine::getQueue Mar 17 10:16:00 volumio volumio[1096]: info: CorePlayQueue::getQueue Mar 17 10:16:00 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.9:/Share/Import_CD /mnt/NAS/N1-051E Mar 17 10:16:00 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:16:00 volumio sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.9:/Share /mnt/NAS/N1-051E Mar 17 10:16:00 volumio sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 17 10:16:00 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: info: Retrieving Cloud Streaming UI Mar 17 10:16:05 volumio volumio[1096]: info: Getting Tidal Cloud Configuration Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: info: Getting Qobuz Cloud Configuration Mar 17 10:16:05 volumio volumio[1096]: info: Asking plugin for UI Config Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: info: Getting Spotify Cloud Configuration Mar 17 10:16:05 volumio volumio[1096]: info: Asking plugin for UI Config Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: info: Saving Spotify Acccount Mar 17 10:16:05 volumio volumio[1096]: info: Got Tidal Cloud Configuration Mar 17 10:16:05 volumio volumio[1096]: info: Got it Mar 17 10:16:05 volumio volumio[1096]: info: Got it Mar 17 10:16:05 volumio volumio[1096]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 10:16:05 volumio volumio[1096]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:05 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 17 10:16:09 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand update Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand update took 5 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatetrue Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 56ms Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 87ms Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 86ms Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: info: Mar 17 10:16:10 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 104 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 103 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 20 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 19 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 18 milliseconds Mar 17 10:16:10 volumio volumio[1096]: info: sendMpdCommand status took 17 milliseconds Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:10 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: 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} Mar 17 10:16:10 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: No code Mar 17 10:16:10 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:10 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:10 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 228ms Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 144ms Mar 17 10:16:10 volumio volumio[1096]: info: ------------------------------ 143ms Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:10 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:13 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:17 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 17 10:16:22 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 17 10:16:25 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand update Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: Mar 17 10:16:26 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand update took 3 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: MPD Database updated - AlbumList cache refreshed Mar 17 10:16:26 volumio volumio[1096]: info: Mar 17 10:16:26 volumio volumio[1096]: ---------------------------- MPD announces state update: database Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: Mar 17 10:16:26 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 6 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:26 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:26 volumio volumio[1096]: 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} Mar 17 10:16:26 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:26 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:26 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:26 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:26 volumio volumio[1096]: info: No code Mar 17 10:16:26 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:26 volumio volumio[1096]: info: ------------------------------ 83ms Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: Mar 17 10:16:26 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: Mar 17 10:16:26 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:26 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 87 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 87 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 86 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 9 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 8 milliseconds Mar 17 10:16:26 volumio volumio[1096]: info: sendMpdCommand status took 7 milliseconds Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:26 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:26 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 217ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 218ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 140ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 138ms Mar 17 10:16:27 volumio volumio[1096]: info: MPD Database updated - AlbumList cache refreshed Mar 17 10:16:27 volumio volumio[1096]: info: Mar 17 10:16:27 volumio volumio[1096]: ---------------------------- MPD announces state update: database Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: info: Mar 17 10:16:27 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: info: MPD Database updated - AlbumList cache refreshed Mar 17 10:16:27 volumio volumio[1096]: info: Mar 17 10:16:27 volumio volumio[1096]: ---------------------------- MPD announces state update: database Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: info: Mar 17 10:16:27 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 24 milliseconds Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 23 milliseconds Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 23 milliseconds Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 22 milliseconds Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 22 milliseconds Mar 17 10:16:27 volumio volumio[1096]: info: sendMpdCommand status took 21 milliseconds Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: 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} Mar 17 10:16:27 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: No code Mar 17 10:16:27 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:27 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:27 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 140ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 141ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 139ms Mar 17 10:16:27 volumio volumio[1096]: info: ------------------------------ 139ms Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:27 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand rescan Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand rescan took 2 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 156ms Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 155ms Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 154ms Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: Mar 17 10:16:29 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 157 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 156 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:29 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:29 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: 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} Mar 17 10:16:29 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: No code Mar 17 10:16:29 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:29 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 283ms Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 130ms Mar 17 10:16:29 volumio volumio[1096]: info: ------------------------------ 129ms Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:29 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:33 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:37 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:41 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand update Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand update took 3 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 5 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 4 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 145ms Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 144ms Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 144ms Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: Mar 17 10:16:44 volumio volumio[1096]: ---------------------------- MPD announces state update: update Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::getState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::sendMpdCommand status Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 147 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 146 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 3 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:44 volumio volumio[1096]: info: sendMpdCommand status took 2 milliseconds Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: Command Router : Notfying DB Updatefalse Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::Close All Modals sent Mar 17 10:16:44 volumio volumio[1096]: verbose: ControllerMpd::parseState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ControllerMpd::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::servicePushState Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: 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} Mar 17 10:16:44 volumio volumio[1096]: verbose: CURRENT POSITION 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState stateService stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::syncState currentStatus stop Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: No code Mar 17 10:16:44 volumio volumio[1096]: info: CoreStateMachine::pushState Mar 17 10:16:44 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Mar 17 10:16:44 volumio volumio[1096]: info: CoreCommandRouter::volumioPushState Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 259ms Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 115ms Mar 17 10:16:44 volumio volumio[1096]: info: ------------------------------ 115ms Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:44 volumio volumio[1096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 17 10:16:45 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:45 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root Mar 17 10:16:45 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 3 ,retrying in 5 seconds Mar 17 10:16:45 volumio sudo[1726]: pam_unix(sudo:session): session closed for user root Mar 17 10:16:45 volumio volumio[1096]: info: Cannot mount NAS N1-051E at system boot, trial number 3 ,retrying in 5 seconds Mar 17 10:16:49 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 10:16:50 volumio volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 10:16:50 volumio volumio[1096]: TypeError: Cannot read property 'replace' of undefined Mar 17 10:16:50 volumio volumio[1096]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Mar 17 10:16:50 volumio volumio[1096]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Mar 17 10:16:50 volumio volumio[1096]: at listOnTimeout (internal/timers.js:554:17) Mar 17 10:16:50 volumio volumio[1096]: at processTimers (internal/timers.js:497:7) Mar 17 10:16:50 volumio volumio[1096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 10:16:50 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.9:/Share/Import_CD /mnt/NAS/N1-051E Mar 17 10:16:50 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 10:16:51 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 10:15 Mar 17 10:16:51 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"