-- Logs begin at Fri 2022-09-16 14:07:28 UTC, end at Fri 2024-07-12 07:57:32 UTC. -- Jul 12 07:56:02 volumio volumio[3294]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:56:02 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 07:56:02 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:02 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:56:02 volumio volumio[3294]: Invalid card number. Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 07:56:03 volumio volumio[3294]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:56:04 volumio volumio[3294]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/0a6eea0d-1d4a-4ab6-8191-49baf83b8300.jpg' Jul 12 07:56:04 volumio volumio[3294]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%83%A1%E6%AD%8C/acd21954-4435-4e41-9ac3-6f2d79d7656f.jpg' Jul 12 07:56:04 volumio volumio[3294]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%BA%AF%E9%9F%B3%E4%B9%90/0530e37a-bd4c-4e2a-b39f-45d6f5dd4f70.jpg' Jul 12 07:56:05 volumio volumio[3294]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E4%BE%9D%E6%9E%97/8cbb4357-c1e2-44b4-95b6-597129ada615.jpg' Jul 12 07:56:11 volumio volumio[3294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 07:56:11 volumio volumio[3294]: { Error: connect ETIMEDOUT 199.16.156.38:443 Jul 12 07:56:11 volumio volumio[3294]: at Object._errnoException (util.js:1022:11) Jul 12 07:56:11 volumio volumio[3294]: at _exceptionWithHostPort (util.js:1044:20) Jul 12 07:56:11 volumio volumio[3294]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 12 07:56:11 volumio volumio[3294]: code: 'ETIMEDOUT', Jul 12 07:56:11 volumio volumio[3294]: errno: 'ETIMEDOUT', Jul 12 07:56:11 volumio volumio[3294]: syscall: 'connect', Jul 12 07:56:11 volumio volumio[3294]: address: '199.16.156.38', Jul 12 07:56:11 volumio volumio[3294]: port: 443 } Jul 12 07:56:11 volumio volumio[3294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 07:56:13 volumio sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 07:55 Jul 12 07:56:13 volumio sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:13 volumio sudo[3792]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:14 volumio volumio-remote-updater[571]: [2024-07-12 07:56:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 12 07:56:14 volumio volumio-remote-updater[571]: [2024-07-12 07:56:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 12 07:56:14 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Jul 12 07:56:14 volumio systemd[1]: Unit volumio.service entered failed state. Jul 12 07:56:14 volumio systemd[1]: Starting dynamicswap service... Jul 12 07:56:14 volumio systemd[1]: Started dynamicswap service. Jul 12 07:56:15 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Jul 12 07:56:15 volumio systemd[1]: Starting dynamicswap service... Jul 12 07:56:15 volumio systemd[1]: Started dynamicswap service. Jul 12 07:56:15 volumio systemd[1]: Stopping Volumio Backend Module... Jul 12 07:56:15 volumio systemd[1]: Starting Volumio Backend Module... Jul 12 07:56:15 volumio systemd[1]: Started Volumio Backend Module. Jul 12 07:56:18 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:18 volumio volumio[3816]: info: ----- Volumio2 ---- Jul 12 07:56:18 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:18 volumio volumio[3816]: info: ----- System startup ---- Jul 12 07:56:18 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:18 volumio volumio[3816]: info: MYVOLUMIO Environment detected Jul 12 07:56:19 volumio volumio[3816]: info: Plugin folders cleanup Jul 12 07:56:19 volumio volumio[3816]: info: Scanning into folder /volumio/app/plugins/ Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category audio_interface Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category miscellanea Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category music_service Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category plugins.json Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category system_controller Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category user_interface Jul 12 07:56:19 volumio volumio[3816]: info: Scanning into folder /data/plugins/ Jul 12 07:56:19 volumio volumio[3816]: info: Scanning category music_service Jul 12 07:56:19 volumio volumio[3816]: info: Plugin folders cleanup completed Jul 12 07:56:19 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:19 volumio volumio[3816]: info: ----- Core plugins startup ---- Jul 12 07:56:19 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:19 volumio volumio[3816]: info: Loading plugins from folder /volumio/app/plugins/ Jul 12 07:56:19 volumio volumio[3816]: info: Adding plugin upnp to MyMusic Plugins Jul 12 07:56:19 volumio volumio[3816]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 12 07:56:19 volumio volumio[3816]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 12 07:56:19 volumio volumio[3816]: info: Loading plugins from folder /data/plugins/ Jul 12 07:56:19 volumio volumio[3816]: info: Loading plugin "system"... Jul 12 07:56:19 volumio volumio[3816]: info: Loading plugin "appearance"... Jul 12 07:56:19 volumio volumio-remote-updater[571]: [2024-07-12 07:56:19] [connect] Successful connection Jul 12 07:56:21 volumio volumio[3816]: info: Loading plugin "network"... Jul 12 07:56:21 volumio volumio[3816]: info: Loading plugin "services"... Jul 12 07:56:21 volumio volumio[3816]: info: Loading plugin "alsa_controller"... Jul 12 07:56:21 volumio volumio[3816]: Invalid card number. Jul 12 07:56:21 volumio volumio[3816]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Jul 12 07:56:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:56:22 volumio volumio[3816]: Invalid card number. Jul 12 07:56:22 volumio volumio[3816]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "wizard"... Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "volumio_command_line_client"... Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "upnp"... Jul 12 07:56:22 volumio volumio[3816]: info: [1720770982231] Starting Upmpd Daemon Jul 12 07:56:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "my_music"... Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "mpd"... Jul 12 07:56:22 volumio volumio[3816]: info: Loading plugin "upnp_browser"... Jul 12 07:56:23 volumio volumio[3816]: info: Loading plugin "networkfs"... Jul 12 07:56:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 07:56:23 volumio volumio[3816]: info: Loading plugin "alarm-clock"... Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "airplay_emulation"... Jul 12 07:56:24 volumio volumio[3816]: info: Starting Shairport Sync Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "last_100"... Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "webradio"... Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "i2s_dacs"... Jul 12 07:56:24 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "volumiodiscovery"... Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 12 07:56:24 volumio node[3816]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 12 07:56:24 volumio node[3816]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 07:56:24 volumio node[3816]: *** WARNING *** For more information see Jul 12 07:56:24 volumio node[3816]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 12 07:56:24 volumio node[3816]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 07:56:24 volumio node[3816]: *** WARNING *** For more information see Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** For more information see Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 07:56:24 volumio volumio[3816]: *** WARNING *** For more information see Jul 12 07:56:24 volumio volumio[3816]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 12 07:56:24 volumio volumio[3816]: Discovery: StartAdv! undefined Jul 12 07:56:24 volumio volumio[3816]: Discovery: Started advertising... Volumio - undefined Jul 12 07:56:24 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "outputs"... Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "albumart"... Jul 12 07:56:24 volumio volumio[3816]: info: Plugin example_plugin is not enabled Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "inputs"... Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "updater_comm"... Jul 12 07:56:24 volumio volumio[3816]: info: Plugin mpdemulation is not enabled Jul 12 07:56:24 volumio volumio[3816]: info: Loading plugin "rest_api"... Jul 12 07:56:25 volumio volumio[3816]: info: Loading plugin "websocket"... Jul 12 07:56:25 volumio volumio[3816]: info: Loading plugin "RoonBridge"... Jul 12 07:56:25 volumio volumio[3816]: Forking 3 albumart workers Jul 12 07:56:25 volumio volumio[3816]: info: Applying required configuration parameters for plugin RoonBridge Jul 12 07:56:25 volumio volumio[3816]: info: ___________ START PLUGINS ___________ Jul 12 07:56:25 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:25 volumio volumio[3816]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 07:56:25 volumio volumio[3816]: info: [1720770985799] CoreMusicLibrary::Adding element Media Servers Jul 12 07:56:25 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:26 volumio volumio[3816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 07:56:26 volumio volumio[3816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 07:56:26 volumio volumio[3816]: info: [1720770986133] CoreMusicLibrary::Adding element Last_100 Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 07:56:26 volumio volumio[3816]: info: [1720770986148] CoreMusicLibrary::Adding element Webradio Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 07:56:26 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:26 volumio volumio[3816]: info: ----- MyVolumio plugins startup ---- Jul 12 07:56:26 volumio volumio[3816]: info: ------------------------------------------- Jul 12 07:56:26 volumio volumio[3816]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 12 07:56:26 volumio volumio[3816]: info: Loading i18n strings for locale zh Jul 12 07:56:26 volumio volumio[3816]: Updating browse sources language Jul 12 07:56:26 volumio volumio[3816]: Cannot find translation for sourceMedia Servers Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:26 volumio sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 12 07:56:26 volumio sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::initPlayerControls Jul 12 07:56:26 volumio systemd[1]: Started RoonBridge. Jul 12 07:56:26 volumio sudo[3889]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: info: BOOT COMPLETED Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:26 volumio volumio[3816]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Jul 12 07:56:26 volumio volumio[3816]: aplay: main:722: audio open error: No such file or directory Jul 12 07:56:26 volumio volumio[3816]: Cannot play startup sound Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::Close All Modals sent Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::Close All Modals sent Jul 12 07:56:26 volumio volumio[3816]: Express server listening on port 3000 Jul 12 07:56:26 volumio volumio[3816]: Volumio Calling Home Jul 12 07:56:26 volumio volumio[3816]: info: CoreStateMachine::resetVolumioState Jul 12 07:56:26 volumio volumio[3816]: info: CoreStateMachine::getcurrentVolume Jul 12 07:56:26 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:27 volumio volumio[3816]: Starting albumart workers Jul 12 07:56:27 volumio volumio[3816]: Starting albumart workers Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 12 07:56:27 volumio volumio[3816]: info: CoreStateMachine::getcurrentVolume Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:27 volumio volumio[3816]: Starting albumart workers Jul 12 07:56:27 volumio volumio[3816]: info: Setting Device type: Raspberry PI Jul 12 07:56:27 volumio volumio[3816]: info: MPD running with PID1565 ,establishing connection Jul 12 07:56:27 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:27 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:27 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:27 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:27 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:56:27 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 12 07:56:27 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 12 07:56:28 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:28 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:56:28 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:28 volumio volumio[3816]: info: Reloading queue from file Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::setRepeat false single undefined Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:28 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::setRandom true Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:28 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:28 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:28 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:28 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:29 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:29 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:29 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:29 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:29 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:29 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:29 volumio volumio[3816]: info: Starting Shairport Sync Jul 12 07:56:29 volumio volumio[3816]: info: Starting Shairport Sync Jul 12 07:56:29 volumio sudo[3933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 07:56:29 volumio sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:29 volumio sudo[3936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 07:56:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:29 volumio sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:29 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 07:56:29 volumio sudo[3933]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:29 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:29 volumio volumio[3816]: Discovery: adding a35ee6e4-a9f3-4159-ad80-1adb06cc6088 Jul 12 07:56:29 volumio volumio[3816]: info: mDNS: Found device Volumio Jul 12 07:56:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 07:56:29 volumio sudo[3936]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:29 volumio volumio[3816]: info: Shairport-Sync Started Jul 12 07:56:29 volumio sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:29 volumio volumio[3816]: Error adding Membership: Error: addMembership EINVAL Jul 12 07:56:29 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:29 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:29 volumio volumio[3816]: info: Shairport-Sync Started Jul 12 07:56:29 volumio volumio[3816]: info: CoreCommandRouter::volumioGetState Jul 12 07:56:29 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:29 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:29 volumio sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:29 volumio sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:29 volumio sudo[3973]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:32 volumio sudo[3988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:32 volumio sudo[3988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:32 volumio sudo[3988]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:32 volumio sudo[3992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 12 07:56:32 volumio sudo[3992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:32 volumio volumio-remote-updater[571]: [2024-07-12 07:56:32] [connect] Successful connection Jul 12 07:56:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 12 07:56:32 volumio volumio-remote-updater[571]: [2024-07-12 07:56:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1720770992 101 Jul 12 07:56:32 volumio sudo[3992]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:32 volumio volumio[3816]: info: Upmpdcli Daemon Started Jul 12 07:56:32 volumio volumio[3816]: info: CoreCommandRouter::volumioGetState Jul 12 07:56:32 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:32 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:32 volumio volumio[3816]: info: CoreCommandRouter::volumioGetQueue Jul 12 07:56:32 volumio volumio[3816]: info: CoreStateMachine::getQueue Jul 12 07:56:32 volumio volumio[3816]: info: CorePlayQueue::getQueue Jul 12 07:56:32 volumio sudo[4006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:32 volumio sudo[4006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:32 volumio sudo[4006]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:32 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 07:56:32 volumio volumio[3816]: info: Listing playlists Jul 12 07:56:32 volumio sudo[4012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:32 volumio sudo[4012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:32 volumio sudo[4012]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:37 volumio volumio[3816]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=a3e00c316a41fc8d5a0f857c60e12752" http://updates.volumio.org:7070/downloader-v1/track-device Jul 12 07:56:37 volumio volumio[3816]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 07:56:37 volumio volumio[3816]: Dload Upload Total Spent Left Speed Jul 12 07:56:37 volumio volumio[3816]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 12 07:56:37 volumio volumio[3816]: retrying in 5 seconds, trial 0 Jul 12 07:56:37 volumio volumio[3816]: Volumio Calling Home Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 12 07:56:38 volumio volumio[3816]: info: Adding plugin bluetooth to MyMusic Plugins Jul 12 07:56:38 volumio volumio[3816]: info: Adding plugin cd_controller to MyMusic Plugins Jul 12 07:56:38 volumio volumio[3816]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 12 07:56:38 volumio volumio[3816]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 12 07:56:41 volumio volumio[3816]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 12 07:56:43 volumio volumio[3816]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 12 07:56:43 volumio volumio[3816]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 12 07:56:43 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:43 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:43 volumio volumio[3816]: info: Starting MyVolumio Remote Streaming Endpoints Jul 12 07:56:43 volumio volumio[3816]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 12 07:56:43 volumio volumio[3816]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 12 07:56:43 volumio volumio[3816]: info: Streaming services startup Jul 12 07:56:43 volumio volumio[3816]: info: Starting Streaming Daemon Jul 12 07:56:43 volumio volumio[3816]: info: MyVolumio not started Jul 12 07:56:43 volumio volumio[3816]: info: Initializing device activation check Jul 12 07:56:43 volumio sudo[4039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 12 07:56:43 volumio sudo[4039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:43 volumio volumio[3816]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 12 07:56:43 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jul 12 07:56:43 volumio systemd[1]: Starting Volumio Streaming Daemon... Jul 12 07:56:43 volumio systemd[1]: Started Volumio Streaming Daemon. Jul 12 07:56:43 volumio sudo[4039]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:43 volumio volumio-streaming-daemon[4045]: ############################ Jul 12 07:56:43 volumio volumio-streaming-daemon[4045]: # Volumio Streaming Daemon # Jul 12 07:56:43 volumio volumio-streaming-daemon[4045]: # Running on port 7777 # Jul 12 07:56:43 volumio volumio-streaming-daemon[4045]: ############################ Jul 12 07:56:46 volumio volumio[3816]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:46 volumio volumio[3816]: info: Enabling I2S DAC: HiFiBerry DAC Plus Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:46 volumio sudo[4061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 12 07:56:46 volumio sudo[4061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio sudo[4061]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:46 volumio volumio[3816]: info: No Overlays Loaded Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:46 volumio volumio[3816]: info: Output device has changed, restarting MPD Jul 12 07:56:46 volumio sudo[4070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Jul 12 07:56:46 volumio sudo[4070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio sudo[4074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 07:56:46 volumio volumio[3816]: info: Output device has changed, restarting Shairport Sync Jul 12 07:56:46 volumio sudo[4074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jul 12 07:56:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Jul 12 07:56:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jul 12 07:56:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jul 12 07:56:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jul 12 07:56:46 volumio sudo[4074]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:46 volumio sudo[4083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 07:56:46 volumio sudo[4070]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:46 volumio sudo[4083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio kernel: pcm512x 1-004d: Linked as a consumer to regulator.1 Jul 12 07:56:46 volumio kernel: pcm512x 1-004d: Failed to reset device: -121 Jul 12 07:56:46 volumio kernel: pcm512x: probe of 1-004d failed with error -121 Jul 12 07:56:46 volumio systemd[1]: Stopping Music Player Daemon... Jul 12 07:56:46 volumio volumio[3816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 07:56:46 volumio volumio[3816]: info: Output device has changed, restarting MPD Jul 12 07:56:46 volumio systemd[1]: Starting Music Player Daemon... Jul 12 07:56:46 volumio sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 07:56:46 volumio volumio[3816]: info: Output device has changed, restarting Shairport Sync Jul 12 07:56:46 volumio sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio sudo[4110]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:46 volumio sudo[4115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 07:56:46 volumio sudo[4115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:46 volumio systemd[1]: Stopping Music Player Daemon... Jul 12 07:56:46 volumio volumio[3816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 07:56:46 volumio systemd[1]: Starting Music Player Daemon... Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:46 volumio volumio[3816]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 12 07:56:46 volumio volumio[3816]: info: Updating Volume Controller Parameters: Device: 1 Name: Hifiberry DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jul 12 07:56:46 volumio volumio[3816]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:56:47 volumio volumio[3816]: info: Fetching Streaming Services browse cache Jul 12 07:56:47 volumio volumio[3816]: error: Upnp client error: Error: This socket has been ended by the other party Jul 12 07:56:47 volumio volumio[3816]: info: MPD Permissions set Jul 12 07:56:47 volumio volumio[3816]: info: MPD Permissions set Jul 12 07:56:47 volumio volumio[3816]: info: I2S Param [object Object] successfully enabled Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:56:47 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:47 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:47 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:47 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:47 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:47 volumio volumio[3816]: info: Starting Shairport Sync Jul 12 07:56:47 volumio sudo[4152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:47 volumio volumio[3816]: info: Starting Shairport Sync Jul 12 07:56:47 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:47 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:47 volumio sudo[4159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 07:56:47 volumio sudo[4159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:48 volumio sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 07:56:48 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 07:56:48 volumio sudo[4159]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 12 07:56:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 07:56:48 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:48 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 12 07:56:48 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateVolumeSettings Jul 12 07:56:48 volumio sudo[4195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 07:56:48 volumio sudo[4195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:48 volumio systemd[1]: Stopping Music Player Daemon... Jul 12 07:56:48 volumio volumio[3816]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 12 07:56:48 volumio volumio[3816]: info: Updating Volume Controller Parameters: Device: 1 Name: Hifiberry DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jul 12 07:56:48 volumio volumio[3816]: info: Shairport-Sync Started Jul 12 07:56:48 volumio volumio[3816]: info: Shairport-Sync Started Jul 12 07:56:48 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:48 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:48 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:48 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:48 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:48 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:48 volumio sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:48 volumio sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:48 volumio sudo[4205]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:48 volumio systemd[1]: Starting Music Player Daemon... Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::getcurrentVolume Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:49 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:49 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:56:49 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 07:56:49 volumio sudo[4219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:49 volumio sudo[4219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:49 volumio sudo[4219]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:49 volumio volumio[3816]: info: VolumeController:: Volume=undefined Mute =false Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:49 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:49 volumio volumio[3816]: info: CorePlayQueue::getTrack 0 Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:49 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:49 volumio sudo[4233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:49 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:50 volumio sudo[4233]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:50 volumio systemd[1]: Started Music Player Daemon. Jul 12 07:56:50 volumio sudo[4083]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:50 volumio sudo[4115]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:50 volumio sudo[4195]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:50 volumio volumio[3816]: error: MPD error: false == true Jul 12 07:56:50 volumio volumio[3816]: error: MPD error: false == true Jul 12 07:56:50 volumio volumio[3816]: info: Jul 12 07:56:53 volumio volumio[3816]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=a3e00c316a41fc8d5a0f857c60e12752" http://updates.volumio.org:7070/downloader-v1/track-device Jul 12 07:56:53 volumio volumio[3816]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 07:56:53 volumio volumio[3816]: Dload Upload Total Spent Left Speed Jul 12 07:56:53 volumio volumio[3816]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 12 07:56:53 volumio volumio[3816]: retrying in 5 seconds, trial 1 Jul 12 07:56:53 volumio volumio[3816]: Volumio Calling Home Jul 12 07:56:55 volumio volumio[3816]: info: Jul 12 07:56:55 volumio volumio[3816]: info: Jul 12 07:56:56 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 07:56:56 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:56:56 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9B%A8%E7%94%9F/630035d5-93e9-4f3f-88c9-1c3564d6e086.jpg' Jul 12 07:56:56 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9C%87%E5%B2%B3/bde0856b-096f-452a-bb6c-79a6c51be240.jpg' Jul 12 07:56:56 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9F%A9%E7%BA%A2/%E6%88%91%E6%98%AF%E6%AD%8C%E6%89%8B%E7%AC%AC%E4%B8%89%E5%AD%A3%20%E7%AC%AC3%E6%9C%9F/d8aed27f-8d63-424f-9d4c-e724ce9bce48.jpg' Jul 12 07:56:56 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%BF%AA%E5%85%8B%E7%89%9B%E4%BB%94/%E9%A3%8E%E9%A3%9E%E6%B2%99/c990fbcf-f3fb-416f-9f0a-2d36db7d8c0b.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%BF%86%E8%8E%B2/ba8e8613-f258-4045-b94b-3c174c321e3c.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E9%9D%99%E8%8C%B9/7d931b0e-4b63-43bd-9ff7-271ac5f5d71d.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9F%A9%E7%BA%A2/%E6%88%91%E6%98%AF%E6%AD%8C%E6%89%8B%E7%AC%AC%E4%B8%89%E5%AD%A3%20%E7%AC%AC2%E6%9C%9F/fd263068-5e2a-49b4-8499-d574a9a73ffb.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%AF%9B%E4%B8%8D%E6%98%93/634bae4d-7b74-4b2f-93bf-d0bb5b2a99fb.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%9F%E7%BE%8E%E7%90%AA/145ca93e-2279-458d-89b0-06be0a2d056a.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E6%88%91%E6%98%AF%E6%AD%8C%E6%89%8B%E7%AC%AC%E4%B8%89%E5%AD%A3%20%E7%AC%AC4%E6%9C%9F/6dd5bc45-5f04-4cab-b22c-727de2a6fd3c.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%AE%97%E7%9B%9B/05a9e5ae-e75c-4569-aa60-4cc9dceb869a.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E5%8A%9B%E5%AE%8F/0218d7be-409f-487b-b287-214a9718c49d.jpg' Jul 12 07:56:57 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E6%9D%B0/9a02b04a-d0a8-48c3-92c8-d8b69bda9166.jpg' Jul 12 07:56:57 volumio volumio[3816]: info: CoreCommandRouter::volumioPlay Jul 12 07:56:57 volumio volumio[3816]: UNSET VOLATILE Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::play index 1 Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::stop Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:56:57 volumio volumio[3816]: UNSET VOLATILE Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::play index undefined Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:56:57 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:57 volumio volumio[3816]: info: CoreStateMachine::startPlaybackTimer Jul 12 07:56:57 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:57 volumio volumio[3816]: info: ControllerMpd::clearAddPlayTracks USB/2E10-1B37/12 无他/02四面楚歌.wav Jul 12 07:56:57 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand stop Jul 12 07:56:57 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand clear Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand add "USB/2E10-1B37/12 无他/02四面楚歌.wav" Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8E%AB%E6%96%87%E8%94%9A/b8c8df44-79c9-4629-84df-8ec13af2db2a.jpg' Jul 12 07:56:58 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E7%BE%8E%E9%9D%99/20bfd4f9-4460-45e6-99a5-e6c95ec49631.jpg' Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 17ms Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand play Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 28ms Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 22ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 19ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 17ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 14ms Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:56:58 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 93ms Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 92ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 20ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 18ms Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: Jul 12 07:56:58 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus stop Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio sudo[4258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio sudo[4264]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4272]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4281]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4289]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4299]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio sudo[4308]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4318]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio sudo[4325]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: sending command... Jul 12 07:56:58 volumio sudo[4335]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:58 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 700ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 701ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 689ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 687ms Jul 12 07:56:58 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 686ms Jul 12 07:56:58 volumio volumio[3816]: info: ------------------------------ 686ms Jul 12 07:56:58 volumio volumio[3816]: info: parsing response... Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:56:58 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":557,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:56:58 volumio volumio[3816]: info: CURRENT POSITION 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:56:58 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:56:58 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:56:58 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:56:58 volumio sudo[4359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:59 volumio sudo[4359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:59 volumio sudo[4359]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:59 volumio volumio[3816]: info: ------------------------------ 772ms Jul 12 07:56:59 volumio sudo[4363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:56:59 volumio sudo[4363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:56:59 volumio sudo[4363]: pam_unix(sudo:session): session closed for user root Jul 12 07:56:59 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E8%8C%B9%E8%8A%B8/b428c922-4dbc-429a-9406-6ae0400df424.jpg' Jul 12 07:56:59 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E6%88%91%E6%98%AF%E6%AD%8C%E6%89%8B%E7%AC%AC%E4%B8%89%E5%AD%A3%20%E7%AC%AC2%E6%9C%9F/c68fd849-8dd6-41ee-883d-28787c685c8e.jpg' Jul 12 07:57:04 volumio volumio[3816]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=a3e00c316a41fc8d5a0f857c60e12752" http://updates.volumio.org:7070/downloader-v1/track-device Jul 12 07:57:05 volumio volumio[3816]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 07:57:05 volumio volumio[3816]: Dload Upload Total Spent Left Speed Jul 12 07:57:05 volumio volumio[3816]: [235B blob data] Jul 12 07:57:05 volumio volumio[3816]: retrying in 5 seconds, trial 2 Jul 12 07:57:05 volumio volumio[3816]: Volumio Calling Home Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 07:57:10 volumio volumio[3816]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioNext Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::next Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::stop Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:57:11 volumio volumio[3816]: UNSET VOLATILE Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::stPlaybackTimer Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::serviceStop Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 1 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::serviceStop Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::stop Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand stop Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio sudo[4386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio sudo[4386]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::play index undefined Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::startPlaybackTimer Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::clearAddPlayTracks USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand stop Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand clear Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand add "USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav" Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":null,"uri":"USB/2E10-1B37/12 无他/02四面楚歌.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService stop Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus stop Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio sudo[4395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: No code Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4395]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio sudo[4400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:11 volumio sudo[4400]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:11 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:11 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:11 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:11 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:11 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:11 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:11 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:11 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:11 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:11 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:11 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:11 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:11 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:11 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:11 volumio sudo[4409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:11 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:11 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:11 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:11 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 284ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 258ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 253ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 251ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 247ms Jul 12 07:57:11 volumio sudo[4409]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 236ms Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 25ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 21ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 18ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 15ms Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand play Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:11 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 89ms Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 75ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 68ms Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 62ms Jul 12 07:57:11 volumio volumio[3816]: info: ------------------------------ 54ms Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: Jul 12 07:57:11 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: sending command... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: parsing response... Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus stop Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:11 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4422]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4422]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio sudo[4425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio sudo[4425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:11 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio sudo[4425]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio sudo[4435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4435]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio sudo[4444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:11 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio sudo[4452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio sudo[4444]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio sudo[4452]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:11 volumio volumio[3816]: info: CURRENT POSITION 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:11 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio sudo[4464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:11 volumio sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:11 volumio sudo[4464]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:11 volumio sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:11 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:11 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:11 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:12 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:12 volumio sudo[4470]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:12 volumio sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:12 volumio sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:12 volumio sudo[4479]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:12 volumio sudo[4490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:12 volumio volumio[3816]: info: ------------------------------ 551ms Jul 12 07:57:12 volumio sudo[4490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:12 volumio volumio[3816]: info: ------------------------------ 541ms Jul 12 07:57:12 volumio volumio[3816]: info: ------------------------------ 539ms Jul 12 07:57:12 volumio volumio[3816]: info: ------------------------------ 538ms Jul 12 07:57:12 volumio volumio[3816]: info: ------------------------------ 538ms Jul 12 07:57:12 volumio sudo[4490]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:12 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%B7%91%E6%A1%A6/5c442313-1d5e-490a-b2e2-e8c697512058.jpg' Jul 12 07:57:17 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%84%E7%A3%8A/0aa8794b-bbf2-4e6a-b926-b2d54ba585f7.jpg' Jul 12 07:57:17 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BD%90%E7%A7%A6/556d4d95-55d4-46f5-83fa-86da412adcf1.jpg' Jul 12 07:57:17 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E5%8D%8E%E5%81%A5/9349dd61-d9ef-4110-9c2e-11fffbe872f3.jpg' Jul 12 07:57:21 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/JJ%20Lin/Wonderland/5e3f57d6-727e-4044-85c3-c5c9d7fe4686.jpg' Jul 12 07:57:21 volumio volumio[3816]: info: CoreCommandRouter::volumioPlay Jul 12 07:57:21 volumio volumio[3816]: UNSET VOLATILE Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::play index 901 Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::stop Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:57:21 volumio volumio[3816]: UNSET VOLATILE Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::stPlaybackTimer Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::updateTrackBlock Jul 12 07:57:21 volumio volumio[3816]: info: CorePlayQueue::getTrackBlock Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:21 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:21 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:21 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::serviceStop Jul 12 07:57:21 volumio volumio[3816]: info: CorePlayQueue::getTrack 916 Jul 12 07:57:21 volumio volumio[3816]: info: CoreCommandRouter::serviceStop Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::stop Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand stop Jul 12 07:57:21 volumio volumio[3816]: info: sending command... Jul 12 07:57:21 volumio sudo[4515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:21 volumio sudo[4515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:21 volumio sudo[4515]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:21 volumio volumio[3816]: info: Jul 12 07:57:21 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:21 volumio volumio[3816]: info: parsing response... Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::play index undefined Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 07:57:21 volumio volumio[3816]: info: sending command... Jul 12 07:57:21 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:21 volumio volumio[3816]: info: CoreStateMachine::startPlaybackTimer Jul 12 07:57:21 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::clearAddPlayTracks USB/2E10-1B37/周华健/周华健-难念的经.ape Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand stop Jul 12 07:57:21 volumio volumio[3816]: info: sending command... Jul 12 07:57:21 volumio volumio[3816]: info: Jul 12 07:57:21 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:21 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand clear Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand add "USB/2E10-1B37/周华健/周华健-难念的经.ape" Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio sudo[4524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"周华健 - 身在梁山.wav","artist":null,"album":null,"uri":"USB/2E10-1B37/周华健《江湖 音乐本事》台版/周华健 - 身在梁山.wav","trackType":"wav"} Jul 12 07:57:22 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState stateService stop Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus stop Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4524]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio sudo[4529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: No code Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:22 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:22 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:22 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:22 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:22 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:22 volumio sudo[4529]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:22 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:22 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:22 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:22 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:22 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:22 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:22 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:22 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:22 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:22 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushError Jul 12 07:57:22 volumio volumio[3816]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jul 12 07:57:22 volumio volumio[3816]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Jul 12 07:57:22 volumio volumio[3816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 12 07:57:22 volumio volumio[3816]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jul 12 07:57:22 volumio volumio[3816]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 12 07:57:22 volumio sudo[4538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio sudo[4538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 254ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 238ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 234ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 230ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 226ms Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio sudo[4538]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 225ms Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 25ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 21ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 19ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 16ms Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand play Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces system playlist update Jul 12 07:57:22 volumio volumio[3816]: info: Ignoring MPD Status Update Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 68ms Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 51ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 49ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 35ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 33ms Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:22 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus stop Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:22 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:22 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio sudo[4551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio sudo[4551]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio sudo[4558]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio sudo[4562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 269ms Jul 12 07:57:22 volumio volumio[3816]: info: ------------------------------ 268ms Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio sudo[4562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio volumio[3816]: info: Jul 12 07:57:22 volumio volumio[3816]: ---------------------------- MPD announces state update: player Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::getState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand status Jul 12 07:57:22 volumio volumio[3816]: info: sending command... Jul 12 07:57:22 volumio sudo[4562]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio sudo[4558]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: parsing response... Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::parseState Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::sendMpdCommand playlistinfo Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:22 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:22 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio sudo[4578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:22 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:22 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio sudo[4578]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio sudo[4582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio sudo[4582]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:22 volumio sudo[4596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:22 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:22 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:22 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:22 volumio sudo[4596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:22 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4596]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4600]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio sudo[4609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio sudo[4609]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio sudo[4618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio sudo[4618]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4631]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio sudo[4631]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio sudo[4637]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio volumio[3816]: info: sending command... Jul 12 07:57:23 volumio volumio[3816]: info: sending command... Jul 12 07:57:23 volumio volumio[3816]: info: sending command... Jul 12 07:57:23 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio sudo[4653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 978ms Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 968ms Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 943ms Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 943ms Jul 12 07:57:23 volumio sudo[4653]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 932ms Jul 12 07:57:23 volumio volumio[3816]: info: parsing response... Jul 12 07:57:23 volumio volumio[3816]: info: parsing response... Jul 12 07:57:23 volumio volumio[3816]: info: parsing response... Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::parseTrackInfo Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":673,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio sudo[4668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":673,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4668]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio sudo[4672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio sudo[4672]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: ControllerMpd::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::servicePushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: STATE SERVICE {"status":"play","position":0,"seek":673,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"难念的经","artist":"周华健","album":null,"uri":"USB/2E10-1B37/周华健/周华健-难念的经.ape","trackType":"ape"} Jul 12 07:57:23 volumio volumio[3816]: info: CURRENT POSITION 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState stateService play Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::syncState currentStatus play Jul 12 07:57:23 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: Received an update from plugin. extracting info from payload Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::pushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreStateMachine::getState Jul 12 07:57:23 volumio volumio[3816]: info: CorePlayQueue::getTrack 901 Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::volumioPushState Jul 12 07:57:23 volumio volumio[3816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 07:57:23 volumio sudo[4690]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio sudo[4700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio sudo[4700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio sudo[4700]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 1129ms Jul 12 07:57:23 volumio sudo[4708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 1126ms Jul 12 07:57:23 volumio volumio[3816]: info: ------------------------------ 1124ms Jul 12 07:57:23 volumio sudo[4708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 07:57:23 volumio sudo[4708]: pam_unix(sudo:session): session closed for user root Jul 12 07:57:24 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E4%BF%8A%E6%9D%B0/fc9f910e-305d-41b0-9661-07bac8bb26f2.jpg' Jul 12 07:57:24 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E4%BF%8A%E6%9D%B0/100%E5%A4%A9/98c46fd1-36e7-4484-a52f-0e591ee08769.jpg' Jul 12 07:57:24 volumio volumio[3816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9F%A9%E7%BA%A2/%E9%A3%9E%E4%BA%91%E4%B9%8B%E4%B8%8B/9fdb658f-a659-4191-ba35-ed20cbaf35a6.jpg' Jul 12 07:57:31 volumio volumio[3816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 07:57:31 volumio volumio[3816]: { Error: connect ETIMEDOUT 199.16.156.38:80 Jul 12 07:57:31 volumio volumio[3816]: at Object._errnoException (util.js:1022:11) Jul 12 07:57:31 volumio volumio[3816]: at _exceptionWithHostPort (util.js:1044:20) Jul 12 07:57:31 volumio volumio[3816]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 12 07:57:31 volumio volumio[3816]: code: 'ETIMEDOUT', Jul 12 07:57:31 volumio volumio[3816]: errno: 'ETIMEDOUT', Jul 12 07:57:31 volumio volumio[3816]: syscall: 'connect', Jul 12 07:57:31 volumio volumio[3816]: address: '199.16.156.38', Jul 12 07:57:31 volumio volumio[3816]: port: 80 } Jul 12 07:57:31 volumio volumio[3816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 07:57:32 volumio sudo[4748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 07:56 Jul 12 07:57:32 volumio sudo[4748]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4"