-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-11-28 07:59:17 UTC. -- Nov 28 07:58:12 volumio sudo[3392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 07:58:12 volumio sudo[3392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:12 volumio sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 07:58:12 volumio sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:12 volumio sudo[3392]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:12 volumio sudo[3395]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:12 volumio volumio[3077]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 5 Nov 28 07:58:12 volumio sudo[3398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 07:58:12 volumio sudo[3398]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:12 volumio sudo[3398]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:12 volumio sudo[3401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 07:58:12 volumio sudo[3401]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:12 volumio sudo[3401]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:12 volumio volumio[3077]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 6 Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::volumioGetState Nov 28 07:58:12 volumio volumio[3077]: info: CorePlayQueue::getTrack 3 Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:12 volumio volumio[3077]: info: Listing playlists Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 28 07:58:12 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 07:58:14 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 28 07:58:18 volumio volumio[3077]: info: Getting Alsa Cards List without I2S DAC Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 28 07:58:18 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 07:58:30 volumio volumio[3077]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Nov 28 07:58:30 volumio sudo[3417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 07:58:30 volumio sudo[3417]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:30 volumio sudo[3417]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:30 volumio volumio[3077]: info: MPD Permissions set Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:30 volumio sudo[3420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 07:58:30 volumio sudo[3420]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:30 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:30 volumio volumio[3077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:31 volumio systemd[1]: mpd.service: Succeeded. Nov 28 07:58:31 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 07:58:31 volumio systemd[1]: Starting Music Player Daemon... Nov 28 07:58:31 volumio mpd[3429]: Nov 28 07:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 07:58:31 volumio mpd[3429]: Nov 28 07:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 28 07:58:31 volumio mpd[3429]: Nov 28 07:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 28 07:58:31 volumio systemd[1]: Started Music Player Daemon. Nov 28 07:58:31 volumio sudo[3420]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:31 volumio volumio[3077]: error: updateQueue error: null Nov 28 07:58:33 volumio volumio[3077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 07:58:33 volumio volumio[3077]: Error: connect ETIMEDOUT 199.59.148.106:443 Nov 28 07:58:33 volumio volumio[3077]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 28 07:58:33 volumio volumio[3077]: errno: -110, Nov 28 07:58:33 volumio volumio[3077]: code: 'ETIMEDOUT', Nov 28 07:58:33 volumio volumio[3077]: syscall: 'connect', Nov 28 07:58:33 volumio volumio[3077]: address: '199.59.148.106', Nov 28 07:58:33 volumio volumio[3077]: port: 443 Nov 28 07:58:33 volumio volumio[3077]: } Nov 28 07:58:33 volumio volumio[3077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 07:58:34 volumio sudo[3442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 07:57 Nov 28 07:58:34 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:34 volumio sudo[3442]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:35 volumio volumio-remote-updater[468]: [2024-11-28 07:58:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 28 07:58:35 volumio volumio-remote-updater[468]: [2024-11-28 07:58:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 28 07:58:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 28 07:58:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 28 07:58:35 volumio systemd[1]: Started dynamicswap service. Nov 28 07:58:35 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 07:58:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 28 07:58:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 28 07:58:35 volumio systemd[1]: Started dynamicswap service. Nov 28 07:58:35 volumio systemd[1]: Stopped Volumio Backend Module. Nov 28 07:58:35 volumio systemd[1]: Started Volumio Backend Module. Nov 28 07:58:35 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 28 07:58:36 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:36 volumio volumio[3457]: info: ----- Volumio3 ---- Nov 28 07:58:36 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:36 volumio volumio[3457]: info: ----- System startup ---- Nov 28 07:58:36 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:36 volumio volumio[3457]: info: MYVOLUMIO Environment detected Nov 28 07:58:36 volumio volumio[3457]: info: Plugin folders cleanup Nov 28 07:58:36 volumio volumio[3457]: info: Scanning into folder /volumio/app/plugins/ Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category audio_interface Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category miscellanea Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category music_service Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category plugins.json Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category system_controller Nov 28 07:58:36 volumio volumio[3457]: info: Scanning category user_interface Nov 28 07:58:36 volumio volumio[3457]: info: Scanning into folder /data/plugins/ Nov 28 07:58:36 volumio volumio[3457]: info: Plugin folders cleanup completed Nov 28 07:58:36 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:36 volumio volumio[3457]: info: ----- Core plugins startup ---- Nov 28 07:58:36 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:36 volumio volumio[3457]: info: Loading plugins from folder /volumio/app/plugins/ Nov 28 07:58:36 volumio volumio[3457]: info: Adding plugin upnp to MyMusic Plugins Nov 28 07:58:36 volumio volumio[3457]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 28 07:58:36 volumio volumio[3457]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugins from folder /data/plugins/ Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "system"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "appearance"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "network"... Nov 28 07:58:37 volumio volumio[3457]: info: Refreshing Cached IP Addresses Nov 28 07:58:37 volumio sudo[3480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 07:58:37 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:37 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:37 volumio sudo[3482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 07:58:37 volumio sudo[3482]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "services"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "alsa_controller"... Nov 28 07:58:37 volumio sudo[3482]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:37 volumio sudo[3485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 07:58:37 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "wizard"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "volumio_command_line_client"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "upnp"... Nov 28 07:58:37 volumio volumio[3457]: info: [1732780717670] Starting Upmpd Daemon Nov 28 07:58:37 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "my_music"... Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "mpd"... Nov 28 07:58:37 volumio volumio[3457]: info: Creating MPD Configuration file Nov 28 07:58:37 volumio sudo[3491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 07:58:37 volumio volumio[3457]: info: Loading plugin "upnp_browser"... Nov 28 07:58:37 volumio sudo[3491]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:37 volumio sudo[3491]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:37 volumio sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 07:58:37 volumio sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:37 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 07:58:37 volumio systemd[1]: mpd.service: Succeeded. Nov 28 07:58:37 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 07:58:37 volumio systemd[1]: Starting Music Player Daemon... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "networkfs"... Nov 28 07:58:38 volumio volumio[3457]: info: Starting Udev Watcher for removable devices Nov 28 07:58:38 volumio volumio[3457]: info: Ignoring mount for partition: boot Nov 28 07:58:38 volumio volumio[3457]: info: Ignoring mount for partition: volumio Nov 28 07:58:38 volumio volumio[3457]: info: Ignoring mount for partition: volumio_data Nov 28 07:58:38 volumio volumio[3457]: info: Mounting Device KINGSTON Nov 28 07:58:38 volumio sudo[3504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 28 07:58:38 volumio sudo[3504]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:38 volumio sudo[3504]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:38 volumio volumio[3457]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Nov 28 07:58:38 volumio volumio[3457]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 28 07:58:38 volumio volumio[3457]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "alarm-clock"... Nov 28 07:58:38 volumio mpd[3498]: Nov 28 07:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 07:58:38 volumio mpd[3498]: Nov 28 07:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 28 07:58:38 volumio mpd[3498]: Nov 28 07:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 28 07:58:38 volumio systemd[1]: Started Music Player Daemon. Nov 28 07:58:38 volumio sudo[3493]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "airplay_emulation"... Nov 28 07:58:38 volumio volumio[3457]: info: Starting Shairport Sync Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "last_100"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "webradio"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "i2s_dacs"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "volumiodiscovery"... Nov 28 07:58:38 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** For more information see Nov 28 07:58:38 volumio node[3457]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 07:58:38 volumio volumio[3457]: *** WARNING *** For more information see Nov 28 07:58:38 volumio node[3457]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 07:58:38 volumio node[3457]: *** WARNING *** For more information see Nov 28 07:58:38 volumio node[3457]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 07:58:38 volumio node[3457]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 07:58:38 volumio node[3457]: *** WARNING *** For more information see Nov 28 07:58:38 volumio volumio[3457]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 28 07:58:38 volumio volumio[3457]: info: Discovery: Started advertising with name: Volumio Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "outputs"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "albumart"... Nov 28 07:58:38 volumio volumio[3457]: info: Plugin example_plugin is not enabled Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "inputs"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "updater_comm"... Nov 28 07:58:38 volumio volumio[3457]: info: Plugin mpdemulation is not enabled Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "rest_api"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading plugin "websocket"... Nov 28 07:58:38 volumio volumio[3457]: info: Loading i18n strings for locale zh Nov 28 07:58:38 volumio volumio[3457]: Updating browse sources language Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::initPlayerControls Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:38 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: Express server listening on port 3000 Nov 28 07:58:39 volumio volumio[3457]: [Metrics] WebUI: 2s 796.57ms Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::resetVolumioState Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::getcurrentVolume Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:39 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:39 volumio volumio[3457]: info: MPD Permissions set Nov 28 07:58:39 volumio volumio[3457]: Forking 3 albumart workers Nov 28 07:58:39 volumio volumio[3457]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 1 Nov 28 07:58:39 volumio volumio[3457]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 2 Nov 28 07:58:39 volumio volumio[3457]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 3 Nov 28 07:58:39 volumio volumio[3457]: info: MPD running with PID3498 Nov 28 07:58:39 volumio volumio[3457]: ,establishing connection Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: Reloading queue from file Nov 28 07:58:39 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::setRepeat null single undefined Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:39 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::setRandom false Nov 28 07:58:39 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:39 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:39 volumio volumio[3457]: info: Setting Device type: Raspberry PI Nov 28 07:58:39 volumio volumio[3457]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 4 Nov 28 07:58:39 volumio volumio[3457]: info: Completed loading Core Plugins Nov 28 07:58:39 volumio volumio[3457]: info: Preparing to generate the ALSA configuration file Nov 28 07:58:39 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:39 volumio volumio[3457]: info: Asound.conf file unchanged, so no further update is needed Nov 28 07:58:39 volumio volumio[3457]: info: Output device has changed, restarting MPD Nov 28 07:58:39 volumio volumio[3457]: info: Output device has changed, restarting Shairport Sync Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:39 volumio sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 07:58:39 volumio sudo[3555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 07:58:39 volumio sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:39 volumio sudo[3555]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:39 volumio sudo[3553]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:39 volumio volumio[3457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 07:58:39 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 07:58:39 volumio volumio[3457]: info: ___________ START PLUGINS ___________ Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 07:58:39 volumio volumio[3457]: info: [1732780719483] CoreMusicLibrary::Adding element Media Servers Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:39 volumio volumio[3457]: Cannot find translation for sourceMedia Servers Nov 28 07:58:39 volumio systemd[1]: mpd.service: Succeeded. Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:39 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 07:58:39 volumio systemd[1]: Starting Music Player Daemon... Nov 28 07:58:39 volumio volumio[3457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:39 volumio volumio[3457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 07:58:39 volumio volumio[3457]: info: [1732780719636] CoreMusicLibrary::Adding element Last_100 Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:39 volumio volumio[3457]: Cannot find translation for sourceMedia Servers Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 07:58:39 volumio volumio[3457]: info: [1732780719640] CoreMusicLibrary::Adding element Webradio Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:39 volumio volumio[3457]: Cannot find translation for sourceMedia Servers Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 07:58:39 volumio volumio[3457]: info: Volumio Calling Home Nov 28 07:58:39 volumio volumio[3457]: info: Completed starting Core Plugins Nov 28 07:58:39 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:39 volumio volumio[3457]: info: ----- MyVolumio plugins startup ---- Nov 28 07:58:39 volumio volumio[3457]: info: ------------------------------------------- Nov 28 07:58:39 volumio volumio[3457]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioGetState Nov 28 07:58:39 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:39 volumio volumio[3457]: Starting albumart workers Nov 28 07:58:39 volumio volumio[3457]: Starting albumart workers Nov 28 07:58:39 volumio volumio[3457]: Starting albumart workers Nov 28 07:58:39 volumio volumio[3457]: info: MPD Permissions set Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 07:58:39 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:39 volumio volumio[3457]: info: Listing playlists Nov 28 07:58:40 volumio volumio[3457]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:40 volumio volumio[3457]: info: Discovery: adding bb26e910-0714-4a2d-9cbb-b7451250e5d0 Nov 28 07:58:40 volumio volumio[3457]: info: mDNS: Found device Volumio Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::volumioGetState Nov 28 07:58:40 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:40 volumio volumio[3457]: info: Starting Shairport Sync Nov 28 07:58:40 volumio volumio[3457]: info: Starting Shairport Sync Nov 28 07:58:40 volumio sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 07:58:40 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:40 volumio volumio[3457]: info: Starting Shairport Sync Nov 28 07:58:40 volumio volumio-remote-updater[468]: [2024-11-28 07:58:40] [connect] Successful connection Nov 28 07:58:40 volumio volumio[3457]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sarah%20Connor/Sarah%20Connor/8faf9808-6673-4229-9281-54e00ef5cdb3.jpg' Nov 28 07:58:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 07:58:40 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 07:58:40 volumio sudo[3582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 07:58:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio sudo[3582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio sudo[3579]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 07:58:40 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 07:58:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio sudo[3582]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:40 volumio mpd[3569]: Nov 28 07:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 07:58:40 volumio mpd[3569]: Nov 28 07:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 28 07:58:40 volumio mpd[3569]: Nov 28 07:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 28 07:58:40 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 07:58:40 volumio systemd[1]: Started Music Player Daemon. Nov 28 07:58:40 volumio sudo[3555]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:40 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 07:58:40 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 07:58:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 07:58:40 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:40 volumio volumio-remote-updater[468]: [2024-11-28 07:58:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732780720 101 Nov 28 07:58:40 volumio volumio[3457]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Nov 28 07:58:40 volumio volumio[3457]: info: Shairport-Sync Started Nov 28 07:58:40 volumio volumio[3457]: Error adding Membership: Error: addMembership EINVAL Nov 28 07:58:40 volumio volumio[3457]: info: Shairport-Sync Started Nov 28 07:58:40 volumio volumio[3457]: info: Volumio called home Nov 28 07:58:40 volumio volumio[3457]: info: Shairport-Sync Started Nov 28 07:58:40 volumio volumio[3457]: info: CoreCommandRouter::volumioGetState Nov 28 07:58:40 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:40 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:41 volumio volumio[3457]: verbose: New Socket.io Connection to 192.168.124.7 from 192.168.124.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Total Clients: 7 Nov 28 07:58:41 volumio volumio[3457]: info: CoreCommandRouter::volumioGetState Nov 28 07:58:41 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:42 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 07:58:42 volumio volumio[3457]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 07:58:42 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 07:58:42 volumio volumio[3457]: info: Listing playlists Nov 28 07:58:43 volumio volumio[3457]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Nov 28 07:58:43 volumio sudo[3623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 07:58:43 volumio sudo[3623]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:43 volumio sudo[3623]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio sudo[3625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 07:58:43 volumio sudo[3625]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:43 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:58:43 volumio volumio[3457]: info: MPD Permissions set Nov 28 07:58:43 volumio systemd[1]: mpd.service: Succeeded. Nov 28 07:58:43 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 07:58:43 volumio systemd[1]: Starting Music Player Daemon... Nov 28 07:58:44 volumio mpd[3635]: Nov 28 07:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 07:58:44 volumio mpd[3635]: Nov 28 07:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 28 07:58:44 volumio mpd[3635]: Nov 28 07:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 28 07:58:44 volumio systemd[1]: Started Music Player Daemon. Nov 28 07:58:44 volumio sudo[3625]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:44 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:47 volumio sudo[3639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 07:58:47 volumio sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:47 volumio sudo[3639]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:47 volumio sudo[3641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 07:58:47 volumio sudo[3641]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:47 volumio sudo[3641]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:47 volumio sudo[3645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 07:58:47 volumio sudo[3645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:47 volumio sudo[3645]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:47 volumio volumio[3457]: info: Upmpdcli Daemon Started Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin bluetooth to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin multiroom to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin metavolumio to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin cd_controller to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 28 07:58:49 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:49 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:58:49 volumio volumio[3457]: info: Starting MyVolumio Remote Streaming Endpoints Nov 28 07:58:49 volumio volumio[3457]: info: MyVolumio not started Nov 28 07:58:49 volumio volumio[3457]: info: Initializing device activation check Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 28 07:58:49 volumio volumio[3457]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 28 07:58:49 volumio volumio[3457]: info: Streaming services startup Nov 28 07:58:49 volumio volumio[3457]: info: Starting Streaming Daemon Nov 28 07:58:49 volumio sudo[3648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 28 07:58:49 volumio sudo[3648]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 07:58:49 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 28 07:58:49 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Nov 28 07:58:49 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Nov 28 07:58:49 volumio systemd[1]: Stopped Volumio Streaming Daemon. Nov 28 07:58:49 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 28 07:58:49 volumio sudo[3648]: pam_unix(sudo:session): session closed for user root Nov 28 07:58:49 volumio volumio-streaming-daemon[3654]: ############################ Nov 28 07:58:49 volumio volumio-streaming-daemon[3654]: # Volumio Streaming Daemon # Nov 28 07:58:49 volumio volumio-streaming-daemon[3654]: # Running on port 7777 # Nov 28 07:58:49 volumio volumio-streaming-daemon[3654]: ############################ Nov 28 07:58:49 volumio volumio-streaming-daemon[3654]: Environment: production Nov 28 07:58:52 volumio volumio-streaming-daemon[3654]: Environment: production Nov 28 07:58:52 volumio volumio[3457]: info: Fetching Streaming Services browse cache Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::volumioPlay Nov 28 07:58:53 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand stop took 1 milliseconds Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:58:53 volumio volumio[3457]: info: Nov 28 07:58:53 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:53 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav" Nov 28 07:58:53 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:53 volumio volumio[3457]: info: Nov 28 07:58:53 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:53 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:53 volumio volumio[3457]: info: ------------------------------ 4ms Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav" took 3 milliseconds Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:58:53 volumio volumio[3457]: info: ------------------------------ 3ms Nov 28 07:58:53 volumio volumio[3457]: info: Nov 28 07:58:53 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand play took 3 milliseconds Nov 28 07:58:53 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:53 volumio volumio[3457]: info: Nov 28 07:58:53 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand status took 10 milliseconds Nov 28 07:58:53 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand status took 6 milliseconds Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:53 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just One Last Dance","artist":"Sarah Connor","album":"Sarah Connor","uri":"USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:53 volumio volumio[3457]: verbose: CURRENT POSITION 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:53 volumio volumio[3457]: info: ------------------------------ 37ms Nov 28 07:58:53 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 28 07:58:53 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:53 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just One Last Dance","artist":"Sarah Connor","album":"Sarah Connor","uri":"USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:53 volumio volumio[3457]: verbose: CURRENT POSITION 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:58:53 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:53 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:53 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:53 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:53 volumio volumio[3457]: info: ------------------------------ 41ms Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:58:56 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 0 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:58:56 volumio volumio[3457]: info: Nov 28 07:58:56 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand stop took 49 milliseconds Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand stop took 2 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:58:56 volumio volumio[3457]: info: Nov 28 07:58:56 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:56 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav" Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just One Last Dance","artist":"Sarah Connor","album":"Sarah Connor","uri":"USB/KINGSTON/192KHZ/[原声母带]Sarah Connor《Just One Last Dance》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:56 volumio volumio[3457]: verbose: CURRENT POSITION 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: No code Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: ------------------------------ 30ms Nov 28 07:58:56 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:56 volumio volumio[3457]: info: Nov 28 07:58:56 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:56 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:56 volumio volumio[3457]: info: ------------------------------ 23ms Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav" took 22 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:58:56 volumio volumio[3457]: info: Nov 28 07:58:56 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:56 volumio volumio[3457]: info: ------------------------------ 8ms Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand play took 6 milliseconds Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:56 volumio volumio[3457]: info: Nov 28 07:58:56 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":393,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"夜深沉","artist":"夜深沉","album":"夜深沉","uri":"USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:56 volumio volumio[3457]: verbose: CURRENT POSITION 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: ------------------------------ 22ms Nov 28 07:58:56 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 28 07:58:56 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:56 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":393,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"夜深沉","artist":"夜深沉","album":"夜深沉","uri":"USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:56 volumio volumio[3457]: verbose: CURRENT POSITION 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:58:56 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:56 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:56 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:56 volumio volumio[3457]: info: ------------------------------ 47ms Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:58:58 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 1 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:58:58 volumio volumio[3457]: info: Nov 28 07:58:58 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand stop took 74 milliseconds Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand stop took 2 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:58:58 volumio volumio[3457]: info: Nov 28 07:58:58 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:58 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav" Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"夜深沉","artist":"夜深沉","album":"夜深沉","uri":"USB/KINGSTON/192KHZ/[原声母带]发烧名曲《夜深沉》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:58:58 volumio volumio[3457]: verbose: CURRENT POSITION 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: No code Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: ------------------------------ 29ms Nov 28 07:58:58 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:58:58 volumio volumio[3457]: info: Nov 28 07:58:58 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:58:58 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:58:58 volumio volumio[3457]: info: ------------------------------ 24ms Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav" took 21 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:58:58 volumio volumio[3457]: info: Nov 28 07:58:58 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:58 volumio volumio[3457]: info: ------------------------------ 7ms Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand play took 6 milliseconds Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:58 volumio volumio[3457]: info: Nov 28 07:58:58 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"无心呢喃","artist":"威猛乐队","album":"威猛乐队","uri":"USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:58:58 volumio volumio[3457]: verbose: CURRENT POSITION 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: ------------------------------ 20ms Nov 28 07:58:58 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 28 07:58:58 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:58:58 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"无心呢喃","artist":"威猛乐队","album":"威猛乐队","uri":"USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:58:58 volumio volumio[3457]: verbose: CURRENT POSITION 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:58:58 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:58:58 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:58:58 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:58:58 volumio volumio[3457]: info: ------------------------------ 40ms Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:01 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 2 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:01 volumio volumio[3457]: info: Nov 28 07:59:01 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand stop took 64 milliseconds Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand stop took 3 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:01 volumio volumio[3457]: info: Nov 28 07:59:01 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:01 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav" Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"无心呢喃","artist":"威猛乐队","album":"威猛乐队","uri":"USB/KINGSTON/192KHZ/[原声母带]威猛乐队《无心呢喃》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:01 volumio volumio[3457]: verbose: CURRENT POSITION 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: No code Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: ------------------------------ 31ms Nov 28 07:59:01 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:01 volumio volumio[3457]: info: Nov 28 07:59:01 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:01 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:01 volumio volumio[3457]: info: ------------------------------ 25ms Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav" took 23 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:01 volumio volumio[3457]: info: Nov 28 07:59:01 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:01 volumio volumio[3457]: info: ------------------------------ 7ms Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand play took 6 milliseconds Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:01 volumio volumio[3457]: info: Nov 28 07:59:01 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"美丽的神话","artist":"孙楠.韩红","album":"孙楠.韩红","uri":"USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:01 volumio volumio[3457]: verbose: CURRENT POSITION 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: ------------------------------ 20ms Nov 28 07:59:01 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 28 07:59:01 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:01 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"美丽的神话","artist":"孙楠.韩红","album":"孙楠.韩红","uri":"USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:01 volumio volumio[3457]: verbose: CURRENT POSITION 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:59:01 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:01 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:01 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:01 volumio volumio[3457]: info: ------------------------------ 41ms Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:04 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 3 Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:59:04 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:04 volumio volumio[3457]: info: Nov 28 07:59:04 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand stop took 21 milliseconds Nov 28 07:59:04 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand stop took 2 milliseconds Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:04 volumio volumio[3457]: info: Nov 28 07:59:04 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:04 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav" Nov 28 07:59:04 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"美丽的神话","artist":"孙楠.韩红","album":"孙楠.韩红","uri":"USB/KINGSTON/192KHZ/[原声母带]孙楠&韩红《美丽的神话》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:04 volumio volumio[3457]: verbose: CURRENT POSITION 4 Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:04 volumio volumio[3457]: info: No code Nov 28 07:59:04 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:04 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:04 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:04 volumio volumio[3457]: info: ------------------------------ 31ms Nov 28 07:59:04 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:04 volumio volumio[3457]: info: Nov 28 07:59:04 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:04 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:04 volumio volumio[3457]: info: ------------------------------ 26ms Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav" took 23 milliseconds Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:04 volumio volumio[3457]: info: Nov 28 07:59:04 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:04 volumio volumio[3457]: info: ------------------------------ 7ms Nov 28 07:59:04 volumio volumio[3457]: info: sendMpdCommand play took 5 milliseconds Nov 28 07:59:04 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:04 volumio volumio[3457]: info: Nov 28 07:59:04 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:04 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:04 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:05 volumio volumio[3457]: info: sendMpdCommand status took 2 milliseconds Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:05 volumio volumio[3457]: info: sendMpdCommand status took 5 milliseconds Nov 28 07:59:05 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:05 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:05 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:05 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","artist":null,"album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:05 volumio volumio[3457]: verbose: CURRENT POSITION 4 Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:05 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:05 volumio volumio[3457]: info: ------------------------------ 23ms Nov 28 07:59:05 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 28 07:59:05 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:05 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:05 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:05 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","artist":null,"album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:05 volumio volumio[3457]: verbose: CURRENT POSITION 4 Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:59:05 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:05 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:05 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:05 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:05 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:05 volumio volumio[3457]: info: ------------------------------ 52ms Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:07 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 4 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:59:07 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:07 volumio volumio[3457]: info: Nov 28 07:59:07 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand stop took 91 milliseconds Nov 28 07:59:07 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]张学友《一路上有你》WAV-192KHZ-64bit(Precise).zip Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand stop took 1 milliseconds Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:07 volumio volumio[3457]: info: Nov 28 07:59:07 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:07 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《一路上有你》WAV-192KHZ-64bit(Precise).zip" Nov 28 07:59:07 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","artist":null,"album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张学友《一千个伤心的理由》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:07 volumio volumio[3457]: verbose: CURRENT POSITION 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: No code Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: ------------------------------ 28ms Nov 28 07:59:07 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:07 volumio volumio[3457]: info: Nov 28 07:59:07 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:07 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:07 volumio volumio[3457]: info: ------------------------------ 22ms Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《一路上有你》WAV-192KHZ-64bit(Precise).zip" took 21 milliseconds Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:07 volumio volumio[3457]: info: Nov 28 07:59:07 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:07 volumio volumio[3457]: info: ------------------------------ 6ms Nov 28 07:59:07 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand status took 5 milliseconds Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand clearerror took 2 milliseconds Nov 28 07:59:07 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 07:59:07 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:07 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[ԭ��ĸ��]��ѧ�ѡ�һ·�����㡷WAV-192KHZ-64bit(Precise).wav","artist":null,"album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张学友《一路上有你》WAV-192KHZ-64bit(Precise).zip/[ԭ��ĸ��]��ѧ�ѡ�һ·�����㡷WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:07 volumio volumio[3457]: verbose: CURRENT POSITION 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: No code Nov 28 07:59:07 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:07 volumio volumio[3457]: info: CorePlayQueue::getTrack 5 Nov 28 07:59:07 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:07 volumio volumio[3457]: info: ------------------------------ 25ms Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:59:09 volumio volumio[3457]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 28 07:59:09 volumio volumio[3457]: info: Completed starting MyVolumio Plugin Nov 28 07:59:09 volumio volumio[3457]: info: BOOT COMPLETED Nov 28 07:59:09 volumio volumio[3457]: [Metrics] CommandRouter: 33s 338.79ms Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::volumiosetStartupVolume Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::Close All Modals sent Nov 28 07:59:09 volumio volumio[3457]: info: CoreCommandRouter::Close All Modals sent Nov 28 07:59:10 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 28 07:59:10 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 28 07:59:10 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:11 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]张学友《情书》WAV-192KHZ-64Pit(Precise).zip Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand stop took 2 milliseconds Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:11 volumio volumio[3457]: info: Nov 28 07:59:11 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:11 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《情书》WAV-192KHZ-64Pit(Precise).zip" Nov 28 07:59:11 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:11 volumio volumio[3457]: info: Nov 28 07:59:11 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:11 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:11 volumio volumio[3457]: info: ------------------------------ 4ms Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张学友《情书》WAV-192KHZ-64Pit(Precise).zip" took 3 milliseconds Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:11 volumio volumio[3457]: info: Nov 28 07:59:11 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:11 volumio volumio[3457]: info: ------------------------------ 5ms Nov 28 07:59:11 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand status took 7 milliseconds Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand clearerror took 2 milliseconds Nov 28 07:59:11 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:11 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:11 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[ԭ��ĸ��]��ѧ�ѡ����顷WAV-192KHZ-64Pit(Precise).wav","artist":null,"album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张学友《情书》WAV-192KHZ-64Pit(Precise).zip/[ԭ��ĸ��]��ѧ�ѡ����顷WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:11 volumio volumio[3457]: verbose: CURRENT POSITION 6 Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:11 volumio volumio[3457]: info: No code Nov 28 07:59:11 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:11 volumio volumio[3457]: info: CorePlayQueue::getTrack 6 Nov 28 07:59:11 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:11 volumio volumio[3457]: info: ------------------------------ 33ms Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:15 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand stop took 2 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:15 volumio volumio[3457]: info: Nov 28 07:59:15 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:15 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand clear took 2 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav" Nov 28 07:59:15 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:15 volumio volumio[3457]: info: Nov 28 07:59:15 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:15 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:15 volumio volumio[3457]: info: ------------------------------ 4ms Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav" took 3 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:15 volumio volumio[3457]: info: ------------------------------ 4ms Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand play took 2 milliseconds Nov 28 07:59:15 volumio volumio[3457]: info: Nov 28 07:59:15 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:15 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:15 volumio volumio[3457]: info: Nov 28 07:59:15 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:15 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand status took 10 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand status took 4 milliseconds Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:15 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"单恋一枝花","artist":"张宇","album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:15 volumio volumio[3457]: verbose: CURRENT POSITION 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:15 volumio volumio[3457]: info: ------------------------------ 32ms Nov 28 07:59:15 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 28 07:59:15 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:15 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"单恋一枝花","artist":"张宇","album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:15 volumio volumio[3457]: verbose: CURRENT POSITION 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:59:15 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:15 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:15 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:15 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:15 volumio volumio[3457]: info: ------------------------------ 53ms Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioNext Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::next Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::stop Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:16 volumio volumio[3457]: verbose: UNSET VOLATILE Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::stPlaybackTimer Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::serviceStop Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 7 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::serviceStop Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::stop Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:16 volumio volumio[3457]: info: Nov 28 07:59:16 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand stop took 61 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::play index undefined Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::startPlaybackTimer Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/192KHZ/[原声母带]张震岳《爱之初体验》WAV-192KHZ-64bit(Precise).wav Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::updateTrackBlock Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrackBlock Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand status took 6 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand stop took 3 milliseconds Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 07:59:16 volumio volumio[3457]: info: Nov 28 07:59:16 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:16 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand clear took 4 milliseconds Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张震岳《爱之初体验》WAV-192KHZ-64bit(Precise).wav" Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"单恋一枝花","artist":"张宇","album":null,"uri":"USB/KINGSTON/192KHZ/[原声母带]张宇《单恋一枝花》WAV-192KHZ-64Pit(Precise).wav","trackType":"wav"} Nov 28 07:59:16 volumio volumio[3457]: verbose: CURRENT POSITION 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState stateService stop Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: No code Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: ------------------------------ 37ms Nov 28 07:59:16 volumio volumio[3457]: error: updateQueue error: null Nov 28 07:59:16 volumio volumio[3457]: info: Nov 28 07:59:16 volumio volumio[3457]: ---------------------------- MPD announces system playlist update Nov 28 07:59:16 volumio volumio[3457]: info: Ignoring MPD Status Update Nov 28 07:59:16 volumio volumio[3457]: info: ------------------------------ 26ms Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand add "USB/KINGSTON/192KHZ/[原声母带]张震岳《爱之初体验》WAV-192KHZ-64bit(Precise).wav" took 23 milliseconds Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand play Nov 28 07:59:16 volumio volumio[3457]: info: ------------------------------ 7ms Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand play took 5 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: Nov 28 07:59:16 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:16 volumio volumio[3457]: info: Nov 28 07:59:16 volumio volumio[3457]: ---------------------------- MPD announces state update: player Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand status took 3 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::getState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand status Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand status took 1 milliseconds Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseState Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"爱之初体验","artist":"张震岳","album":"张震岳","uri":"USB/KINGSTON/192KHZ/[原声母带]张震岳《爱之初体验》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:16 volumio volumio[3457]: verbose: CURRENT POSITION 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus stop Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: ------------------------------ 18ms Nov 28 07:59:16 volumio volumio[3457]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 28 07:59:16 volumio volumio[3457]: verbose: ControllerMpd::parseTrackInfo Nov 28 07:59:16 volumio volumio[3457]: info: ControllerMpd::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::servicePushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":192,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"爱之初体验","artist":"张震岳","album":"张震岳","uri":"USB/KINGSTON/192KHZ/[原声母带]张震岳《爱之初体验》WAV-192KHZ-64bit(Precise).wav","trackType":"wav"} Nov 28 07:59:16 volumio volumio[3457]: verbose: CURRENT POSITION 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState stateService play Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::syncState currentStatus play Nov 28 07:59:16 volumio volumio[3457]: info: Received an update from plugin. extracting info from payload Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: CoreStateMachine::pushState Nov 28 07:59:16 volumio volumio[3457]: info: CorePlayQueue::getTrack 8 Nov 28 07:59:16 volumio volumio[3457]: info: CoreCommandRouter::volumioPushState Nov 28 07:59:16 volumio volumio[3457]: info: ------------------------------ 37ms Nov 28 07:59:17 volumio volumio[3457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 07:59:17 volumio volumio[3457]: Error: connect ECONNREFUSED 103.200.30.143:80 Nov 28 07:59:17 volumio volumio[3457]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 28 07:59:17 volumio volumio[3457]: errno: -111, Nov 28 07:59:17 volumio volumio[3457]: code: 'ECONNREFUSED', Nov 28 07:59:17 volumio volumio[3457]: syscall: 'connect', Nov 28 07:59:17 volumio volumio[3457]: address: '103.200.30.143', Nov 28 07:59:17 volumio volumio[3457]: port: 80 Nov 28 07:59:17 volumio volumio[3457]: } Nov 28 07:59:17 volumio volumio[3457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 07:59:17 volumio sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 07:58 Nov 28 07:59:17 volumio sudo[3681]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"