-- Logs begin at Thu 2019-02-14 18:11:59 HKT, end at Fri 2024-03-29 14:12:45 HKT. -- Mar 29 14:11:09 w-volumio volumio[1165]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Mar 29 14:11:09 w-volumio volumio[1165]: info: Preparing to generate the ALSA configuration file Mar 29 14:11:09 w-volumio volumio[1165]: info: Asound.conf file unchanged, so no further update is needed Mar 29 14:11:09 w-volumio volumio[1165]: info: Output device has changed, restarting MPD Mar 29 14:11:09 w-volumio volumio[1165]: info: Output device has changed, restarting Shairport Sync Mar 29 14:11:09 w-volumio sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:09 w-volumio sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:09 w-volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:09 w-volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 14:11:09 w-volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:09 w-volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 14:11:09 w-volumio volumio[1165]: info: ___________ START PLUGINS ___________ Mar 29 14:11:09 w-volumio volumio[1165]: info: ControllerMpd::onStart: Initializing MPD Mar 29 14:11:09 w-volumio volumio[1165]: info: Creating MPD Configuration file Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:09 w-volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 14:11:09 w-volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 14:11:09 w-volumio volumio[1165]: info: [1711692669344] CoreMusicLibrary::Adding element Media Servers Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:09 w-volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:09 w-volumio sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 14:11:09 w-volumio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:09 w-volumio systemd[1]: Listening on mpd.socket. Mar 29 14:11:09 w-volumio systemd[1]: Starting Music Player Daemon... Mar 29 14:11:09 w-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 29 14:11:09 w-volumio systemd[1]: mpd.service: Succeeded. Mar 29 14:11:09 w-volumio systemd[1]: Stopped Music Player Daemon. Mar 29 14:11:09 w-volumio systemd[1]: Starting Music Player Daemon... Mar 29 14:11:09 w-volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:09 w-volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 14:11:09 w-volumio volumio[1165]: info: [1711692669531] CoreMusicLibrary::Adding element Last_100 Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 14:11:09 w-volumio volumio[1165]: info: [1711692669538] CoreMusicLibrary::Adding element Webradio Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 14:11:09 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 14:11:09 w-volumio volumio[1165]: info: Volumio Calling Home Mar 29 14:11:09 w-volumio volumio[1165]: info: MPD Permissions set Mar 29 14:11:09 w-volumio volumio[1165]: info: MPD Permissions set Mar 29 14:11:10 w-volumio volumio[1165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:10 w-volumio volumio[1165]: info: Volumio called home Mar 29 14:11:10 w-volumio volumio[1165]: info: Starting Shairport Sync Mar 29 14:11:10 w-volumio volumio[1165]: info: Starting Shairport Sync Mar 29 14:11:10 w-volumio volumio[1165]: info: Starting Shairport Sync Mar 29 14:11:10 w-volumio sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 14:11:10 w-volumio sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:10 w-volumio sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 14:11:10 w-volumio sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:10 w-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 14:11:10 w-volumio systemd[1]: shairport-sync.service: Succeeded. Mar 29 14:11:10 w-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 14:11:10 w-volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 14:11:10 w-volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:10 w-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 14:11:10 w-volumio sudo[1354]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:10 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Mar 29 14:11:10 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:10 w-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 14:11:10 w-volumio volumio[1165]: info: Shairport-Sync Started Mar 29 14:11:10 w-volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 29 14:11:10 w-volumio systemd[1]: shairport-sync.service: Succeeded. Mar 29 14:11:10 w-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 14:11:10 w-volumio volumio[1165]: Error adding Membership: Error: addMembership EINVAL Mar 29 14:11:10 w-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 14:11:10 w-volumio sudo[1356]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:10 w-volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:10 w-volumio volumio[1165]: info: Shairport-Sync Started Mar 29 14:11:10 w-volumio volumio[1165]: info: Shairport-Sync Started Mar 29 14:11:13 w-volumio mpd[1338]: Mar 29 14:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 14:11:13 w-volumio systemd[1]: Started Music Player Daemon. Mar 29 14:11:13 w-volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:13 w-volumio sudo[1331]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:13 w-volumio volumio[1165]: info: Completed starting Core Plugins Mar 29 14:11:13 w-volumio volumio[1165]: info: ------------------------------------------- Mar 29 14:11:13 w-volumio volumio[1165]: info: ----- MyVolumio plugins startup ---- Mar 29 14:11:13 w-volumio volumio[1165]: info: ------------------------------------------- Mar 29 14:11:13 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 29 14:11:13 w-volumio volumio[1165]: error: MPD error: The expression evaluated to a falsy value: Mar 29 14:11:13 w-volumio volumio[1165]: assert.ok(self.idling) Mar 29 14:11:13 w-volumio volumio[1165]: error: The expression evaluated to a falsy value: Mar 29 14:11:13 w-volumio volumio[1165]: assert.ok(self.idling) Mar 29 14:11:13 w-volumio volumio[1165]: info: MPD running with PID1338 Mar 29 14:11:13 w-volumio volumio[1165]: ,establishing connection Mar 29 14:11:13 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:13 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:14 w-volumio volumio[1006]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 29 14:11:16 w-volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 14:11:18 w-volumio volumio[1165]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin metavolumio to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 14:11:21 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 14:11:24 w-volumio volumio[1165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 14:11:24 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 14:11:24 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:24 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:24 w-volumio volumio[1165]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 14:11:24 w-volumio volumio[1165]: info: MyVolumio not started Mar 29 14:11:24 w-volumio volumio[1165]: info: Initializing device activation check Mar 29 14:11:24 w-volumio volumio[1165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 14:11:24 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 29 14:11:24 w-volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 14:11:26 w-volumio volumio[1165]: info: Starting Streaming Service Transparent Proxy Mar 29 14:11:26 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 29 14:11:26 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 29 14:11:26 w-volumio volumio[1165]: info: Streaming services startup Mar 29 14:11:26 w-volumio volumio[1165]: info: Starting Streaming Daemon Mar 29 14:11:26 w-volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 14:11:26 w-volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:11:26 w-volumio volumio[1165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 29 14:11:26 w-volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: info: Retrieving Cloud Streaming UI Mar 29 14:11:26 w-volumio volumio[1165]: error: Cannot start Volumio Streaming Daemon Mar 29 14:11:26 w-volumio volumio[1165]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 14:11:26 w-volumio volumio[1165]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 14:11:26 w-volumio volumio[1165]: info: Getting Tidal Cloud Configuration Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: info: Getting Qobuz Cloud Configuration Mar 29 14:11:26 w-volumio volumio[1165]: info: Asking plugin for UI Config Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: info: Getting Spotify Cloud Configuration Mar 29 14:11:26 w-volumio volumio[1165]: info: Asking plugin for UI Config Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: info: Saving Spotify Acccount Mar 29 14:11:26 w-volumio volumio[1165]: info: Got Tidal Cloud Configuration Mar 29 14:11:26 w-volumio volumio[1165]: info: Got it Mar 29 14:11:26 w-volumio volumio[1165]: info: Got it Mar 29 14:11:26 w-volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:26 w-volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 29 14:11:26 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: info: Retrieving Cloud Streaming UI Mar 29 14:11:27 w-volumio volumio[1165]: info: Getting Tidal Cloud Configuration Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: info: Getting Qobuz Cloud Configuration Mar 29 14:11:27 w-volumio volumio[1165]: info: Asking plugin for UI Config Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: info: Getting Spotify Cloud Configuration Mar 29 14:11:27 w-volumio volumio[1165]: info: Asking plugin for UI Config Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: info: Saving Spotify Acccount Mar 29 14:11:27 w-volumio volumio[1165]: info: Got Tidal Cloud Configuration Mar 29 14:11:27 w-volumio volumio[1165]: info: Got it Mar 29 14:11:27 w-volumio volumio[1165]: info: Got it Mar 29 14:11:27 w-volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:11:27 w-volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:11:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 14:11:28 w-volumio volumio[1165]: STREAMING PROXY: Starting server on port 3245 Mar 29 14:11:28 w-volumio volumio[1165]: Node JS runtime: 14 Mar 29 14:11:31 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:11:31 w-volumio volumio[1006]: ........................................................................................................++++ Mar 29 14:11:31 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand rescan Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: info: Mar 29 14:11:31 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand rescan took 14 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: info: Mar 29 14:11:31 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:31 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: info: Mar 29 14:11:31 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:31 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:31 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 33 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 26 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 26 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 24 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 25 milliseconds Mar 29 14:11:31 w-volumio volumio[1165]: info: sendMpdCommand status took 18 milliseconds Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: 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 29 14:11:32 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: No code Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: 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 29 14:11:32 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: No code Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: 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 29 14:11:32 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: No code Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: ------------------------------ 647ms Mar 29 14:11:32 w-volumio volumio[1165]: info: ------------------------------ 639ms Mar 29 14:11:32 w-volumio volumio[1165]: info: ------------------------------ 635ms Mar 29 14:11:32 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: Mar 29 14:11:32 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:32 w-volumio volumio[1165]: info: sendMpdCommand status took 128 milliseconds Mar 29 14:11:32 w-volumio volumio[1165]: info: sendMpdCommand status took 113 milliseconds Mar 29 14:11:32 w-volumio volumio[1165]: info: sendMpdCommand status took 112 milliseconds Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: 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 29 14:11:32 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: No code Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: 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 29 14:11:32 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:32 w-volumio volumio[1165]: info: No code Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:32 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:32 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 407ms Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 408ms Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 393 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 391 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 391 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 387 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 386 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: info: sendMpdCommand status took 385 milliseconds Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:33 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: 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 29 14:11:33 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: No code Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: 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 29 14:11:33 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: No code Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: 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 29 14:11:33 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: No code Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: 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 29 14:11:33 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: No code Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:33 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 849ms Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 852ms Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 847ms Mar 29 14:11:33 w-volumio volumio[1165]: info: ------------------------------ 849ms Mar 29 14:11:34 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand rescan Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:34 w-volumio volumio[1165]: info: Mar 29 14:11:34 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:34 w-volumio volumio[1165]: info: sendMpdCommand rescan took 7 milliseconds Mar 29 14:11:34 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:34 w-volumio volumio[1165]: info: Mar 29 14:11:34 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:34 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:34 w-volumio volumio[1165]: info: sendMpdCommand status took 13 milliseconds Mar 29 14:11:34 w-volumio volumio[1165]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:11:34 w-volumio volumio[1165]: info: sendMpdCommand status took 8 milliseconds Mar 29 14:11:34 w-volumio volumio[1165]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:11:34 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:34 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:34 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ------------------------------ 320ms Mar 29 14:11:35 w-volumio volumio[1165]: info: ------------------------------ 314ms Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 15 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 13 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ------------------------------ 191ms Mar 29 14:11:35 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: Mar 29 14:11:35 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 254 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 254 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 254 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ------------------------------ 485ms Mar 29 14:11:35 w-volumio volumio[1165]: info: ------------------------------ 486ms Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 549 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 548 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 549 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 367 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 366 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: info: sendMpdCommand status took 366 milliseconds Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: 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 29 14:11:35 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: No code Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:35 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:35 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:35 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: 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 29 14:11:36 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: No code Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 1002ms Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 1003ms Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 822ms Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 823ms Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand update Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand update took 4 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 20 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 16 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 13 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 11 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 10 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 8 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatetrue Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: 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 29 14:11:36 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: No code Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: 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 29 14:11:36 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: No code Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: 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 29 14:11:36 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: No code Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:36 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:36 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 518ms Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 511ms Mar 29 14:11:36 w-volumio volumio[1165]: info: ------------------------------ 508ms Mar 29 14:11:36 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: MPD Database updated - AlbumList cache refreshed Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: database Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: Mar 29 14:11:36 w-volumio volumio[1165]: ---------------------------- MPD announces state update: update Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 103 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 101 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: info: sendMpdCommand status took 101 milliseconds Mar 29 14:11:36 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 510ms Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 511ms Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 511 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 508 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 508 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 503 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 502 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: info: sendMpdCommand status took 501 milliseconds Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: info: Command Router : Notfying DB Updatefalse Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: 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 29 14:11:37 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: No code Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:37 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:37 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 1028ms Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 1030ms Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 1023ms Mar 29 14:11:37 w-volumio volumio[1165]: info: ------------------------------ 1024ms Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 14:11:39 w-volumio volumio[1165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Mar 29 14:11:39 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 14:11:39 w-volumio volumio-remote-updater[502]: No test mode Mar 29 14:11:39 w-volumio volumio-remote-updater[502]: No alpha test mode Mar 29 14:11:39 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 14:11:40 w-volumio volumio[1165]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 14:11:40 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 14:11:41 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:11:41 w-volumio volumio[1165]: info: CURURI: music-library Mar 29 14:11:41 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:42 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:11:42 w-volumio volumio[1165]: info: CURURI: music-library/USB Mar 29 14:11:42 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:43 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:11:43 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G Mar 29 14:11:43 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:11:44 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:11:46 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE] Mar 29 14:11:46 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:46 w-volumio volumio[1165]: info: Preloading song: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:46 w-volumio volumio[1165]: info: Exploding uri music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav in service mpd Mar 29 14:11:46 w-volumio volumio[1165]: info: ALBUMART /albumart?cacheid=208&web=//large&path=%2Fmnt%2FUSB%2FUSB-8G%2F%40%20TIS%E3%80%8A%E8%A5%BF%E7%94%B5%E7%8E%8B%20%E7%88%B5%E5%A3%AB%E6%B4%BB%E4%BD%9B%20Blueeolution%E3%80%8B%E4%BA%BA%E5%A3%B0%E5%8F%91%E7%83%A7%E7%A2%9F%5BWAV%2BCUE%5D%2F%E8%A5%BF%E7%94%B5%E7%8E%8B%20%E7%88%B5%E5%A3%AB%E6%B4%BB%E4%BD%9B.wav&metadata=false Mar 29 14:11:46 w-volumio volumio[1165]: info: URI /mnt/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:46 w-volumio volumio[1165]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 29 14:11:46 w-volumio volumio[1165]: info: Completed starting MyVolumio Plugin Mar 29 14:11:46 w-volumio volumio[1165]: info: BOOT COMPLETED Mar 29 14:11:46 w-volumio volumio[1165]: [Metrics] CommandRouter: 73s 202.49ms Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:46 w-volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:11:47 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 14:11:47 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 14:11:47 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 14:11:48 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:11:48 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:48 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:48 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:48 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:48 w-volumio volumio[1165]: info: Exploding uri music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] in service mpd Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:48 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:11:48 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::clearAddPlayTracks USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand stop took 9 milliseconds Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand clear took 15 milliseconds Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 18ms Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" took 14 milliseconds Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 12ms Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 11ms Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:48 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 22ms Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand play took 17 milliseconds Mar 29 14:11:48 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 16ms Mar 29 14:11:48 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:48 w-volumio volumio[1165]: info: ------------------------------ 13ms Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:48 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:48 w-volumio volumio[1165]: info: Mar 29 14:11:48 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:48 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand status took 43 milliseconds Mar 29 14:11:48 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 14:11:48 w-volumio volumio[1165]: info: sendMpdCommand status took 46 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand status took 42 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand status took 42 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:49 w-volumio volumio[1165]: info: Mar 29 14:11:49 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand clearerror took 94 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand clearerror took 91 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand clearerror took 87 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand clearerror took 84 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand status took 21 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:49 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:49 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:49 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:49 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:49 w-volumio volumio[1165]: info: ------------------------------ 345ms Mar 29 14:11:49 w-volumio volumio[1165]: info: ------------------------------ 346ms Mar 29 14:11:49 w-volumio volumio[1165]: info: ------------------------------ 338ms Mar 29 14:11:49 w-volumio volumio[1165]: info: ------------------------------ 335ms Mar 29 14:11:49 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 238 milliseconds Mar 29 14:11:49 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:49 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:49 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:49 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:49 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:49 w-volumio volumio[1165]: info: ------------------------------ 330ms Mar 29 14:11:53 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:11:53 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:53 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:53 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:53 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:53 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::clearAddPlayTracks USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand stop took 5 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:53 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand clear took 3 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:53 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 11ms Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" took 8 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: 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 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: No code Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 150ms Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:53 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 157 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 157 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 156ms Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand play took 154 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 16ms Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: 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 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: No code Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: 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 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: No code Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 367ms Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 366ms Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:53 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 277 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 276 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 69 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 68ms Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:53 w-volumio volumio[1165]: info: Mar 29 14:11:53 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: info: sendMpdCommand status took 6 milliseconds Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:53 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:53 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:53 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:53 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:53 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:53 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:53 w-volumio volumio[1165]: info: ------------------------------ 377ms Mar 29 14:11:54 w-volumio volumio[1165]: info: ------------------------------ 478ms Mar 29 14:11:54 w-volumio volumio[1165]: info: ------------------------------ 273ms Mar 29 14:11:54 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 185 milliseconds Mar 29 14:11:54 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:54 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:54 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:54 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:54 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:54 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:54 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:54 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:54 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:54 w-volumio volumio[1165]: info: ------------------------------ 279ms Mar 29 14:11:55 w-volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 14:11:56 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::serviceStop Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::serviceStop Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::stop Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:11:56 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:56 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:56 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:56 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:56 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::clearAddPlayTracks USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand stop took 80 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand stop took 23 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 24 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand clear took 24 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 22 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 20 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: 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 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: No code Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: 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 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: No code Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 221ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 221ms Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 215ms Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 211 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" took 210 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 12ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 9ms Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:11:56 w-volumio volumio[1165]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 248ms Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:56 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 75ms Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand play took 69 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 67ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 65ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 14 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 13 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 10 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 8 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: Mar 29 14:11:56 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 12 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:56 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:56 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:56 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 124ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 260ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 255ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 253ms Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 219 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 219 milliseconds Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:56 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:56 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:56 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:56 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:56 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 365ms Mar 29 14:11:56 w-volumio volumio[1165]: info: ------------------------------ 363ms Mar 29 14:11:57 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::serviceStop Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::serviceStop Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::stop Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:11:57 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:11:57 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:57 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:57 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:57 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::clearAddPlayTracks USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand stop took 75 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand stop took 18 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 34 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand clear took 33 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 31 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 29 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: 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 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: No code Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: 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 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: No code Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 222ms Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 222ms Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 241ms Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 226 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand add "USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav" took 226 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 29ms Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 27ms Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:11:57 w-volumio volumio[1165]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 270ms Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:11:57 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 75ms Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand play took 71 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 68ms Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 68ms Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 16 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 16 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 13 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 10 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: Mar 29 14:11:57 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 9 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: info: sendMpdCommand status took 6 milliseconds Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:11:57 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:57 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:57 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:57 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:57 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:57 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:57 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 105ms Mar 29 14:11:57 w-volumio volumio[1165]: info: ------------------------------ 249ms Mar 29 14:11:58 w-volumio volumio[1165]: info: ------------------------------ 243ms Mar 29 14:11:58 w-volumio volumio[1165]: info: ------------------------------ 242ms Mar 29 14:11:58 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 209 milliseconds Mar 29 14:11:58 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 210 milliseconds Mar 29 14:11:58 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:58 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:11:58 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:58 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:58 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:58 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:11:58 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Mar 29 14:11:58 w-volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:11:58 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:11:58 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:11:58 w-volumio volumio[1165]: info: ------------------------------ 372ms Mar 29 14:11:58 w-volumio volumio[1165]: info: ------------------------------ 371ms Mar 29 14:12:01 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:12:01 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE] Mar 29 14:12:01 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:08 w-volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 14:12:12 w-volumio volumio[1006]: .............................................................................................................................................................................................................++++ Mar 29 14:12:12 w-volumio volumio[1006]: e is 65537 (0x010001) Mar 29 14:12:12 w-volumio volumio[1006]: writing RSA key Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::servicePause Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::servicePause Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::pause Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand pause took 1 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: info: Mar 29 14:12:12 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:12 w-volumio volumio[1165]: info: Mar 29 14:12:12 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:12 w-volumio volumio[1165]: info: Mar 29 14:12:12 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand status took 3 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:12 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15970,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:12:12 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15970,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:12:12 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:12 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15970,"duration":2486,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西电王 爵士活佛.wav","artist":null,"album":null,"uri":"USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/西电王 爵士活佛.wav","trackType":"wav"} Mar 29 14:12:12 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus pause Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:12 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:12 w-volumio volumio[1165]: info: ------------------------------ 49ms Mar 29 14:12:12 w-volumio volumio[1165]: info: ------------------------------ 47ms Mar 29 14:12:12 w-volumio volumio[1165]: info: ------------------------------ 47ms Mar 29 14:12:14 w-volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 14:12:15 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:15 w-volumio volumio[1165]: info: Mar 29 14:12:15 w-volumio volumio[1165]: [1711692735117] ---------------------------- Client requests add and Play Volumio CUE entry Mar 29 14:12:15 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 29 14:12:15 w-volumio volumio[1165]: info: Adding CUE individual entry: 2 USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE]/西电王 爵士活佛.cue Mar 29 14:12:15 w-volumio volumio[1165]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 14:12:15 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:15 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:15 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:15 w-volumio volumio[1165]: info: Adding Item to queue: cue://USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE]/西电王 爵士活佛.cue@2 Mar 29 14:12:15 w-volumio volumio[1165]: info: Exploding uri cue://USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE]/西电王 爵士活佛.cue@2 in service mpd Mar 29 14:12:15 w-volumio volumio[1165]: info: Error: Mar 29 14:12:15 w-volumio volumio[1165]: Error: EISDIR: illegal operation on a directory, read Mar 29 14:12:15 w-volumio volumio[1165]: at Object.readSync (fs.js:592:3) Mar 29 14:12:15 w-volumio volumio[1165]: at tryReadSync (fs.js:366:20) Mar 29 14:12:15 w-volumio volumio[1165]: at Object.readFileSync (fs.js:403:19) Mar 29 14:12:15 w-volumio volumio[1165]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:39:43) Mar 29 14:12:15 w-volumio volumio[1165]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1924:27) Mar 29 14:12:15 w-volumio volumio[1165]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Mar 29 14:12:15 w-volumio volumio[1165]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 29 14:12:15 w-volumio volumio[1165]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 29 14:12:15 w-volumio volumio[1165]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 29 14:12:15 w-volumio volumio[1165]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 29 14:12:15 w-volumio volumio[1165]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Mar 29 14:12:15 w-volumio volumio[1165]: at ControllerMpd.addPlayCue (/volumio/app/plugins/music_service/mpd/index.js:157:24) Mar 29 14:12:15 w-volumio volumio[1165]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Mar 29 14:12:15 w-volumio volumio[1165]: at Promise._successFn (/volumio/app/plugins/user_interface/websocket/index.js:134:37) Mar 29 14:12:15 w-volumio volumio[1165]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 14:12:15 w-volumio volumio[1165]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 29 14:12:16 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::serviceStop Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::serviceStop Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::stop Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:16 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:16 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE]/西电王 爵士活佛.cue Mar 29 14:12:16 w-volumio volumio[1165]: info: Exploding uri music-library/USB/USB-8G/@ TIS《西电王 爵士活佛 Blueeolution》人声发烧碟[WAV+CUE]/TIS《西电王 爵士活佛Blueeolution》[WAV+CUE]/西电王 爵士活佛.cue in service mpd Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand stop took 341 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand stop took 11 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces state update: player Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::getState Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:16 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand status took 7 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand clear took 7 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::parseState Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: 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 29 14:12:16 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: No code Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: ControllerMpd::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::servicePushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: 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 29 14:12:16 w-volumio volumio[1165]: verbose: CURRENT POSITION 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: No code Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreStateMachine::pushState Mar 29 14:12:16 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:16 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 35ms Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 34ms Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:16 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:16 w-volumio volumio[1165]: info: Mar 29 14:12:16 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:16 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:16 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:16 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:16 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 129ms Mar 29 14:12:16 w-volumio volumio[1165]: info: sendMpdCommand playlistinfo took 128 milliseconds Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 17ms Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 15ms Mar 29 14:12:16 w-volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:12:16 w-volumio volumio[1165]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 14:12:16 w-volumio volumio[1165]: info: ------------------------------ 142ms Mar 29 14:12:19 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:19 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:19 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:19 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:19 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:19 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:19 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:19 w-volumio volumio[1165]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:12:19 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:19 w-volumio volumio[1165]: info: sendMpdCommand clear took 1 milliseconds Mar 29 14:12:19 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:19 w-volumio volumio[1165]: info: Mar 29 14:12:19 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:19 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:19 w-volumio volumio[1165]: info: Mar 29 14:12:19 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:19 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:19 w-volumio volumio[1165]: info: Mar 29 14:12:19 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:19 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:19 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:19 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:19 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:19 w-volumio volumio[1165]: info: ------------------------------ 5ms Mar 29 14:12:19 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:19 w-volumio volumio[1165]: info: ------------------------------ 3ms Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:21 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:21 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:21 w-volumio volumio[1165]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 7ms Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 3ms Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:21 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:21 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:21 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:21 w-volumio volumio[1165]: info: sendMpdCommand stop took 2 milliseconds Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:21 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: info: Mar 29 14:12:21 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:21 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 7ms Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:21 w-volumio volumio[1165]: info: ------------------------------ 3ms Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:22 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:22 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:22 w-volumio volumio[1165]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:22 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:22 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 7ms Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 3ms Mar 29 14:12:22 w-volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:22 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:22 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:22 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:22 w-volumio volumio[1165]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: info: sendMpdCommand clear took 2 milliseconds Mar 29 14:12:22 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: info: Mar 29 14:12:22 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:22 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:22 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 7ms Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:22 w-volumio volumio[1165]: info: ------------------------------ 2ms Mar 29 14:12:26 w-volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 14:12:27 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:12:27 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE] Mar 29 14:12:27 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:28 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:12:28 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3 Mar 29 14:12:28 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:32 w-volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 14:12:33 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:33 w-volumio volumio[1165]: info: Mar 29 14:12:33 w-volumio volumio[1165]: [1711692753051] ---------------------------- Client requests add and Play Volumio CUE entry Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 29 14:12:33 w-volumio volumio[1165]: info: Adding CUE individual entry: 3 USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:33 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:33 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:33 w-volumio volumio[1165]: info: Adding Item to queue: cue://USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue@3 Mar 29 14:12:33 w-volumio volumio[1165]: info: Exploding uri cue://USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue@3 in service mpd Mar 29 14:12:33 w-volumio volumio[1165]: info: Error: Mar 29 14:12:33 w-volumio volumio[1165]: Error: EISDIR: illegal operation on a directory, read Mar 29 14:12:33 w-volumio volumio[1165]: at Object.readSync (fs.js:592:3) Mar 29 14:12:33 w-volumio volumio[1165]: at tryReadSync (fs.js:366:20) Mar 29 14:12:33 w-volumio volumio[1165]: at Object.readFileSync (fs.js:403:19) Mar 29 14:12:33 w-volumio volumio[1165]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:39:43) Mar 29 14:12:33 w-volumio volumio[1165]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1924:27) Mar 29 14:12:33 w-volumio volumio[1165]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Mar 29 14:12:33 w-volumio volumio[1165]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 29 14:12:33 w-volumio volumio[1165]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 29 14:12:33 w-volumio volumio[1165]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 29 14:12:33 w-volumio volumio[1165]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 29 14:12:33 w-volumio volumio[1165]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Mar 29 14:12:33 w-volumio volumio[1165]: at ControllerMpd.addPlayCue (/volumio/app/plugins/music_service/mpd/index.js:157:24) Mar 29 14:12:33 w-volumio volumio[1165]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Mar 29 14:12:33 w-volumio volumio[1165]: at Promise._successFn (/volumio/app/plugins/user_interface/websocket/index.js:134:37) Mar 29 14:12:33 w-volumio volumio[1165]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 14:12:33 w-volumio volumio[1165]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 29 14:12:33 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:33 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:33 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:33 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:34 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:34 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:34 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:34 w-volumio volumio[1165]: info: Exploding uri music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue in service mpd Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:34 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:34 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:34 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:34 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:34 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:34 w-volumio volumio[1165]: info: sendMpdCommand stop took 9 milliseconds Mar 29 14:12:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:34 w-volumio volumio[1165]: info: Mar 29 14:12:34 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:34 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:34 w-volumio volumio[1165]: info: sendMpdCommand clear took 7 milliseconds Mar 29 14:12:34 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:34 w-volumio volumio[1165]: info: Mar 29 14:12:34 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:34 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:34 w-volumio volumio[1165]: info: Mar 29 14:12:34 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:34 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:34 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:34 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:34 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:34 w-volumio volumio[1165]: info: ------------------------------ 9ms Mar 29 14:12:34 w-volumio volumio[1165]: info: ------------------------------ 6ms Mar 29 14:12:34 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:38 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:38 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:38 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:38 w-volumio volumio[1165]: info: Using cached record of: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::play index 0 Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::play index undefined Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:38 w-volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:12:38 w-volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Mar 29 14:12:38 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:12:38 w-volumio volumio[1165]: info: sendMpdCommand stop took 4 milliseconds Mar 29 14:12:38 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:12:38 w-volumio volumio[1165]: info: Mar 29 14:12:38 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:38 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:38 w-volumio volumio[1165]: info: sendMpdCommand clear took 6 milliseconds Mar 29 14:12:38 w-volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "USB/USB-8G/" Mar 29 14:12:38 w-volumio volumio[1165]: info: Mar 29 14:12:38 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:38 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:38 w-volumio volumio[1165]: info: Mar 29 14:12:38 w-volumio volumio[1165]: ---------------------------- MPD announces system playlist update Mar 29 14:12:38 w-volumio volumio[1165]: info: Ignoring MPD Status Update Mar 29 14:12:38 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:38 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:38 w-volumio volumio[1165]: error: updateQueue error: null Mar 29 14:12:38 w-volumio volumio[1165]: info: ------------------------------ 10ms Mar 29 14:12:38 w-volumio volumio[1165]: info: ------------------------------ 6ms Mar 29 14:12:38 w-volumio volumio[1165]: info: ------------------------------ 4ms Mar 29 14:12:43 w-volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 14:12:43 w-volumio volumio[1165]: info: CURURI: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE] Mar 29 14:12:43 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:44 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreStateMachine::ClearQueue Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreStateMachine::stop Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:12:44 w-volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Mar 29 14:12:44 w-volumio volumio[1165]: info: CorePlayQueue::saveQueue Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:12:44 w-volumio volumio[1165]: info: CoreStateMachine::addQueueItems Mar 29 14:12:44 w-volumio volumio[1165]: info: CorePlayQueue::addQueueItems Mar 29 14:12:44 w-volumio volumio[1165]: info: Preload queue cleared Mar 29 14:12:44 w-volumio volumio[1165]: info: Adding Item to queue: music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3 Mar 29 14:12:44 w-volumio volumio[1165]: info: Exploding uri music-library/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3 in service mpd Mar 29 14:12:44 w-volumio volumio[1165]: info: ALBUMART /albumart?cacheid=208&web=/Snow%20Rose%203/large&path=%2Fmnt%2FUSB%2FUSB-8G%2F%40%20%E5%8F%91%E7%83%A7%E5%A5%B3%E5%A3%B0%20Milan%20Snow%20%E9%9B%AA%E5%84%BF%E3%80%8ASnow%20Rose%203%E3%80%8B%5BFLAC%2BCUE%5D%2FMilan.Snow.-.Snow.Rose.3%2FCDImage.cue&metadata=false Mar 29 14:12:44 w-volumio volumio[1165]: info: URI /mnt/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:44 w-volumio volumio[1165]: info: ALBUMART /albumart?cacheid=208&web=/Snow%20Rose%203/large&path=%2Fmnt%2FUSB%2FUSB-8G%2F%40%20%E5%8F%91%E7%83%A7%E5%A5%B3%E5%A3%B0%20Milan%20Snow%20%E9%9B%AA%E5%84%BF%E3%80%8ASnow%20Rose%203%E3%80%8B%5BFLAC%2BCUE%5D%2FMilan.Snow.-.Snow.Rose.3%2FCDImage.cue&metadata=false Mar 29 14:12:44 w-volumio volumio[1165]: info: URI /mnt/USB/USB-8G/@ 发烧女声 Milan Snow 雪儿《Snow Rose 3》[FLAC+CUE]/Milan.Snow.-.Snow.Rose.3/CDImage.cue Mar 29 14:12:44 w-volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:12:44 w-volumio volumio[1165]: Error: Unable to resolve or reject the same promise twice Mar 29 14:12:44 w-volumio volumio[1165]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 14:12:44 w-volumio volumio[1165]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Mar 29 14:12:44 w-volumio volumio[1165]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 14:12:44 w-volumio volumio[1165]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 14:12:44 w-volumio volumio[1165]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 14:12:44 w-volumio volumio[1165]: at Socket.emit (events.js:315:20) Mar 29 14:12:44 w-volumio volumio[1165]: at addChunk (internal/streams/readable.js:309:12) Mar 29 14:12:44 w-volumio volumio[1165]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 29 14:12:44 w-volumio volumio[1165]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 29 14:12:44 w-volumio volumio[1165]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 29 14:12:44 w-volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:12:45 w-volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 14:11 Mar 29 14:12:45 w-volumio sudo[1626]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"