-- Logs begin at Tue 2024-08-20 08:44:54 UTC, end at Tue 2024-08-20 08:49:53 UTC. -- Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand status Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand clear Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: Aug 20 08:48:00 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 20 08:48:00 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseState Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand add "USB/2E10-1B37/单曲/Christine Welch-一百万个可能.flac" Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: Aug 20 08:48:00 volumio volumio[1037]: info: Aug 20 08:48:00 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 20 08:48:00 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 20 08:48:00 volumio volumio[1037]: info: ------------------------------ 13ms Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseTrackInfo Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand play Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::pushError Aug 20 08:48:00 volumio volumio[1037]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Aug 20 08:48:00 volumio volumio[1037]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Aug 20 08:48:00 volumio volumio[1037]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 20 08:48:00 volumio volumio[1037]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 20 08:48:00 volumio volumio[1037]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: ------------------------------ 46ms Aug 20 08:48:00 volumio volumio[1037]: info: ------------------------------ 25ms Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: Aug 20 08:48:00 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::getState Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand status Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: Aug 20 08:48:00 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::getState Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand status Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseState Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseState Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseTrackInfo Aug 20 08:48:00 volumio volumio[1037]: info: sending command... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::pushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState Aug 20 08:48:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 626 Aug 20 08:48:00 volumio volumio[1037]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"一百万个可能","artist":"Christine Welch","album":"一百万个可能","uri":"USB/2E10-1B37/单曲/Christine Welch-一百万个可能.flac","trackType":"flac"} Aug 20 08:48:00 volumio volumio[1037]: info: CURRENT POSITION 626 Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::getState Aug 20 08:48:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 626 Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:00 volumio volumio[1037]: info: ------------------------------ 84ms Aug 20 08:48:00 volumio volumio[1037]: info: parsing response... Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::parseTrackInfo Aug 20 08:48:00 volumio volumio[1037]: info: ControllerMpd::pushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState Aug 20 08:48:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 626 Aug 20 08:48:00 volumio volumio[1037]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"一百万个可能","artist":"Christine Welch","album":"一百万个可能","uri":"USB/2E10-1B37/单曲/Christine Welch-一百万个可能.flac","trackType":"flac"} Aug 20 08:48:00 volumio volumio[1037]: info: CURRENT POSITION 626 Aug 20 08:48:00 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 20 08:48:00 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::getState Aug 20 08:48:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 626 Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:00 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:00 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreStateMachine::getState Aug 20 08:48:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 626 Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:00 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:00 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:00 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:00 volumio volumio[1037]: info: ------------------------------ 193ms Aug 20 08:48:00 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:00 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:00 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:25 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:48:25 volumio volumio[1037]: { Error: connect ETIMEDOUT 128.242.250.157:80 Aug 20 08:48:25 volumio volumio[1037]: at Object._errnoException (util.js:1022:11) Aug 20 08:48:25 volumio volumio[1037]: at _exceptionWithHostPort (util.js:1044:20) Aug 20 08:48:25 volumio volumio[1037]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 20 08:48:25 volumio volumio[1037]: code: 'ETIMEDOUT', Aug 20 08:48:25 volumio volumio[1037]: errno: 'ETIMEDOUT', Aug 20 08:48:25 volumio volumio[1037]: syscall: 'connect', Aug 20 08:48:25 volumio volumio[1037]: address: '128.242.250.157', Aug 20 08:48:25 volumio volumio[1037]: port: 80 } Aug 20 08:48:25 volumio volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:48:26 volumio sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 08:47 Aug 20 08:48:26 volumio sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:26 volumio sudo[1720]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:28 volumio volumio-remote-updater[563]: [2024-08-20 08:48:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 20 08:48:28 volumio volumio-remote-updater[563]: [2024-08-20 08:48:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 20 08:48:28 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Aug 20 08:48:29 volumio systemd[1]: Unit volumio.service entered failed state. Aug 20 08:48:29 volumio systemd[1]: Starting dynamicswap service... Aug 20 08:48:29 volumio systemd[1]: Started dynamicswap service. Aug 20 08:48:29 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Aug 20 08:48:29 volumio systemd[1]: Starting dynamicswap service... Aug 20 08:48:29 volumio systemd[1]: Started dynamicswap service. Aug 20 08:48:29 volumio systemd[1]: Stopping Volumio Backend Module... Aug 20 08:48:29 volumio systemd[1]: Starting Volumio Backend Module... Aug 20 08:48:29 volumio systemd[1]: Started Volumio Backend Module. Aug 20 08:48:32 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:32 volumio volumio[1744]: info: ----- Volumio2 ---- Aug 20 08:48:32 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:32 volumio volumio[1744]: info: ----- System startup ---- Aug 20 08:48:32 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:32 volumio volumio[1744]: info: MYVOLUMIO Environment detected Aug 20 08:48:33 volumio volumio[1744]: info: Plugin folders cleanup Aug 20 08:48:33 volumio volumio[1744]: info: Scanning into folder /volumio/app/plugins/ Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category audio_interface Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category miscellanea Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category music_service Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category plugins.json Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category system_controller Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category user_interface Aug 20 08:48:33 volumio volumio[1744]: info: Scanning into folder /data/plugins/ Aug 20 08:48:33 volumio volumio[1744]: info: Scanning category music_service Aug 20 08:48:33 volumio volumio[1744]: info: Plugin folders cleanup completed Aug 20 08:48:33 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:33 volumio volumio[1744]: info: ----- Core plugins startup ---- Aug 20 08:48:33 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:33 volumio volumio[1744]: info: Loading plugins from folder /volumio/app/plugins/ Aug 20 08:48:33 volumio volumio[1744]: info: Adding plugin upnp to MyMusic Plugins Aug 20 08:48:33 volumio volumio[1744]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 20 08:48:33 volumio volumio[1744]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 20 08:48:33 volumio volumio[1744]: info: Loading plugins from folder /data/plugins/ Aug 20 08:48:33 volumio volumio[1744]: info: Loading plugin "system"... Aug 20 08:48:33 volumio volumio[1744]: info: Loading plugin "appearance"... Aug 20 08:48:33 volumio volumio-remote-updater[563]: [2024-08-20 08:48:33] [connect] Successful connection Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "network"... Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "services"... Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "alsa_controller"... Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "wizard"... Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "volumio_command_line_client"... Aug 20 08:48:35 volumio volumio[1744]: info: Loading plugin "upnp"... Aug 20 08:48:36 volumio volumio[1744]: info: [1724143716003] Starting Upmpd Daemon Aug 20 08:48:36 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 08:48:36 volumio volumio[1744]: info: Loading plugin "my_music"... Aug 20 08:48:36 volumio volumio[1744]: info: Loading plugin "mpd"... Aug 20 08:48:36 volumio volumio[1744]: info: Loading plugin "upnp_browser"... Aug 20 08:48:37 volumio volumio[1744]: info: Loading plugin "networkfs"... Aug 20 08:48:37 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 08:48:37 volumio volumio[1744]: info: Loading plugin "alarm-clock"... Aug 20 08:48:37 volumio volumio[1744]: info: Loading plugin "airplay_emulation"... Aug 20 08:48:37 volumio volumio[1744]: info: Starting Shairport Sync Aug 20 08:48:37 volumio volumio[1744]: info: Loading plugin "last_100"... Aug 20 08:48:37 volumio volumio[1744]: info: Loading plugin "webradio"... Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "i2s_dacs"... Aug 20 08:48:38 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "volumiodiscovery"... Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 08:48:38 volumio node[1744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 08:48:38 volumio node[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 08:48:38 volumio node[1744]: *** WARNING *** For more information see Aug 20 08:48:38 volumio node[1744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 08:48:38 volumio node[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 08:48:38 volumio node[1744]: *** WARNING *** For more information see Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** For more information see Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 08:48:38 volumio volumio[1744]: *** WARNING *** For more information see Aug 20 08:48:38 volumio volumio[1744]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 20 08:48:38 volumio volumio[1744]: Discovery: StartAdv! undefined Aug 20 08:48:38 volumio volumio[1744]: Discovery: Started advertising... Volumio - undefined Aug 20 08:48:38 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "outputs"... Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "albumart"... Aug 20 08:48:38 volumio volumio[1744]: info: Plugin example_plugin is not enabled Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "inputs"... Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "updater_comm"... Aug 20 08:48:38 volumio volumio[1744]: info: Plugin mpdemulation is not enabled Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "rest_api"... Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "websocket"... Aug 20 08:48:38 volumio volumio[1744]: info: Loading plugin "RoonBridge"... Aug 20 08:48:39 volumio volumio[1744]: Forking 3 albumart workers Aug 20 08:48:39 volumio volumio[1744]: info: Applying required configuration parameters for plugin RoonBridge Aug 20 08:48:39 volumio volumio[1744]: info: ___________ START PLUGINS ___________ Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 08:48:39 volumio volumio[1744]: info: [1724143719487] CoreMusicLibrary::Adding element Media Servers Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:48:39 volumio volumio[1744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 08:48:39 volumio volumio[1744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 08:48:39 volumio volumio[1744]: info: [1724143719737] CoreMusicLibrary::Adding element Last_100 Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 08:48:39 volumio volumio[1744]: info: [1724143719746] CoreMusicLibrary::Adding element Webradio Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 08:48:39 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:39 volumio volumio[1744]: info: ----- MyVolumio plugins startup ---- Aug 20 08:48:39 volumio volumio[1744]: info: ------------------------------------------- Aug 20 08:48:39 volumio volumio[1744]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 20 08:48:39 volumio volumio[1744]: info: Loading i18n strings for locale zh Aug 20 08:48:39 volumio volumio[1744]: Updating browse sources language Aug 20 08:48:39 volumio volumio[1744]: Cannot find translation for sourceMedia Servers Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:48:39 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Aug 20 08:48:39 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::initPlayerControls Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:39 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 20 08:48:39 volumio systemd[1]: Started RoonBridge. Aug 20 08:48:40 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: info: BOOT COMPLETED Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 08:48:40 volumio volumio[1744]: aplay: main:722: audio open error: Device or resource busy Aug 20 08:48:40 volumio volumio[1744]: Cannot play startup sound Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::Close All Modals sent Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::Close All Modals sent Aug 20 08:48:40 volumio volumio[1744]: Express server listening on port 3000 Aug 20 08:48:40 volumio volumio[1744]: Volumio Calling Home Aug 20 08:48:40 volumio volumio[1744]: info: CoreStateMachine::resetVolumioState Aug 20 08:48:40 volumio volumio[1744]: info: CoreStateMachine::getcurrentVolume Aug 20 08:48:40 volumio volumio[1744]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 08:48:40 volumio volumio[1744]: Starting albumart workers Aug 20 08:48:40 volumio volumio[1744]: Starting albumart workers Aug 20 08:48:40 volumio volumio[1744]: info: Setting Device type: Raspberry PI Aug 20 08:48:40 volumio volumio[1744]: info: MPD running with PID614 ,establishing connection Aug 20 08:48:40 volumio volumio[1744]: Starting albumart workers Aug 20 08:48:41 volumio volumio[1744]: info: VolumeController:: Volume=undefined Mute =false Aug 20 08:48:41 volumio volumio[1744]: info: CoreStateMachine::pushState Aug 20 08:48:41 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:41 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:41 volumio volumio[1744]: info: CoreStateMachine::updateTrackBlock Aug 20 08:48:41 volumio volumio[1744]: info: CorePlayQueue::getTrackBlock Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 20 08:48:41 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 20 08:48:41 volumio volumio[1744]: info: Reloading queue from file Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::setRepeat true single undefined Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::pushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:42 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::setRandom true Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::pushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:42 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:42 volumio volumio[1744]: info: VolumeController:: Volume=undefined Mute =false Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::pushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:42 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Aug 20 08:48:42 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:48:42 volumio volumio[1744]: info: Starting Shairport Sync Aug 20 08:48:42 volumio volumio[1744]: info: Starting Shairport Sync Aug 20 08:48:42 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 08:48:42 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:42 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 08:48:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 08:48:42 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:42 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 20 08:48:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 08:48:42 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 08:48:42 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 20 08:48:42 volumio volumio[1744]: Discovery: adding a35ee6e4-a9f3-4159-ad80-1adb06cc6088 Aug 20 08:48:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 08:48:42 volumio volumio[1744]: info: mDNS: Found device Volumio Aug 20 08:48:42 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:42 volumio sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:42 volumio volumio[1744]: info: Shairport-Sync Started Aug 20 08:48:42 volumio volumio[1744]: Error adding Membership: Error: addMembership EINVAL Aug 20 08:48:42 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:42 volumio volumio[1744]: info: Shairport-Sync Started Aug 20 08:48:42 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:43 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Aug 20 08:48:43 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:43 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:43 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:43 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:43 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:46 volumio sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:46 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:46 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:46 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 20 08:48:46 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:46 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 20 08:48:46 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:46 volumio volumio[1744]: info: Upmpdcli Daemon Started Aug 20 08:48:46 volumio volumio-remote-updater[563]: [2024-08-20 08:48:46] [connect] Successful connection Aug 20 08:48:46 volumio volumio-remote-updater[563]: [2024-08-20 08:48:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724143726 101 Aug 20 08:48:50 volumio volumio[1744]: 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 Aug 20 08:48:50 volumio volumio[1744]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 20 08:48:50 volumio volumio[1744]: Dload Upload Total Spent Left Speed Aug 20 08:48:50 volumio volumio[1744]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 20 08:48:50 volumio volumio[1744]: retrying in 5 seconds, trial 0 Aug 20 08:48:50 volumio volumio[1744]: Volumio Calling Home Aug 20 08:48:55 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Aug 20 08:48:55 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:48:55 volumio volumio[1744]: info: CorePlayQueue::getTrack 0 Aug 20 08:48:55 volumio sudo[1950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:55 volumio volumio[1744]: info: CoreCommandRouter::volumioGetQueue Aug 20 08:48:55 volumio volumio[1744]: info: CoreStateMachine::getQueue Aug 20 08:48:55 volumio volumio[1744]: info: CorePlayQueue::getQueue Aug 20 08:48:55 volumio volumio[1744]: info: CoreCommandRouter::volumioGetVisibleSources Aug 20 08:48:55 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:48:55 volumio sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:55 volumio volumio[1744]: info: Listing playlists Aug 20 08:48:55 volumio sudo[1950]: pam_unix(sudo:session): session closed for user root Aug 20 08:48:56 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 20 08:48:56 volumio sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:48:56 volumio sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:48:56 volumio sudo[1959]: pam_unix(sudo:session): session closed for user root Aug 20 08:49:01 volumio volumio[1744]: 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 Aug 20 08:49:01 volumio volumio[1744]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 20 08:49:01 volumio volumio[1744]: Dload Upload Total Spent Left Speed Aug 20 08:49:01 volumio volumio[1744]: [235B blob data] Aug 20 08:49:01 volumio volumio[1744]: retrying in 5 seconds, trial 1 Aug 20 08:49:01 volumio volumio[1744]: Volumio Calling Home Aug 20 08:49:08 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%99%E6%A5%A0/c4e389d1-5ff1-4550-9e76-8162814617d0.jpg' Aug 20 08:49:08 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%99%E7%87%95%E5%A7%BF/%E6%88%91%E8%A6%81%E7%9A%84%E5%B9%B8%E7%A6%8F/8806dd4d-d73f-41be-886b-7caf703e4c39.jpg' Aug 20 08:49:08 volumio volumio[1744]: 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' Aug 20 08:49:08 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9D%93%E9%A2%96/e5c3813c-da2c-4f63-9bf3-62665b0347c8.jpg' Aug 20 08:49:08 volumio volumio[1744]: 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' Aug 20 08:49:08 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9F%B6%E6%B6%B5/4593b1cf-688b-476a-ad84-6510964640cc.jpg' Aug 20 08:49:08 volumio volumio[1744]: 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' Aug 20 08:49:09 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 08:49:09 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 08:49:09 volumio volumio[1744]: 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' Aug 20 08:49:11 volumio volumio[1744]: 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 Aug 20 08:49:11 volumio volumio[1744]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 20 08:49:11 volumio volumio[1744]: Dload Upload Total Spent Left Speed Aug 20 08:49:11 volumio volumio[1744]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 20 08:49:11 volumio volumio[1744]: retrying in 5 seconds, trial 2 Aug 20 08:49:11 volumio volumio[1744]: Volumio Calling Home Aug 20 08:49:17 volumio volumio[1744]: 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' Aug 20 08:49:17 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%85%E8%89%B3%E8%8A%B3/8cd9b341-f1e5-44df-83fa-b3af32e1c88e.jpg' Aug 20 08:49:17 volumio volumio[1744]: info: CoreCommandRouter::volumioPlay Aug 20 08:49:17 volumio volumio[1744]: UNSET VOLATILE Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::play index 715 Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::stop Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:49:17 volumio volumio[1744]: UNSET VOLATILE Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::play index undefined Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:49:17 volumio volumio[1744]: info: CorePlayQueue::getTrack 715 Aug 20 08:49:17 volumio volumio[1744]: info: CoreStateMachine::startPlaybackTimer Aug 20 08:49:17 volumio volumio[1744]: info: CorePlayQueue::getTrack 715 Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::clearAddPlayTracks USB/2E10-1B37/单曲/毛不易-牧马城市-(电视剧《老男孩》片尾曲).wav Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand stop Aug 20 08:49:17 volumio volumio[1744]: info: sending command... Aug 20 08:49:17 volumio volumio[1744]: 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' Aug 20 08:49:17 volumio volumio[1744]: info: Aug 20 08:49:17 volumio volumio[1744]: ---------------------------- MPD announces state update: player Aug 20 08:49:17 volumio volumio[1744]: info: parsing response... Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::getState Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand status Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand clear Aug 20 08:49:17 volumio volumio[1744]: info: sending command... Aug 20 08:49:17 volumio volumio[1744]: info: sending command... Aug 20 08:49:17 volumio volumio[1744]: info: Aug 20 08:49:17 volumio volumio[1744]: ---------------------------- MPD announces system playlist update Aug 20 08:49:17 volumio volumio[1744]: info: Ignoring MPD Status Update Aug 20 08:49:17 volumio volumio[1744]: info: parsing response... Aug 20 08:49:17 volumio volumio[1744]: info: parsing response... Aug 20 08:49:17 volumio volumio[1744]: info: ControllerMpd::parseState Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand add "USB/2E10-1B37/单曲/毛不易-牧马城市-(电视剧《老男孩》片尾曲).wav" Aug 20 08:49:18 volumio volumio[1744]: info: sending command... Aug 20 08:49:18 volumio volumio[1744]: info: sending command... Aug 20 08:49:18 volumio volumio[1744]: info: Aug 20 08:49:18 volumio volumio[1744]: info: Aug 20 08:49:18 volumio volumio[1744]: ---------------------------- MPD announces system playlist update Aug 20 08:49:18 volumio volumio[1744]: info: Ignoring MPD Status Update Aug 20 08:49:18 volumio volumio[1744]: info: ------------------------------ 32ms Aug 20 08:49:18 volumio volumio[1744]: info: parsing response... Aug 20 08:49:18 volumio volumio[1744]: info: parsing response... Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::parseTrackInfo Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand play Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::pushError Aug 20 08:49:18 volumio volumio[1744]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Aug 20 08:49:18 volumio volumio[1744]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:295:72) Aug 20 08:49:18 volumio volumio[1744]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 20 08:49:18 volumio volumio[1744]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 20 08:49:18 volumio volumio[1744]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 20 08:49:18 volumio volumio[1744]: info: sending command... Aug 20 08:49:18 volumio volumio[1744]: info: ------------------------------ 73ms Aug 20 08:49:18 volumio volumio[1744]: info: ------------------------------ 32ms Aug 20 08:49:18 volumio volumio[1744]: info: parsing response... Aug 20 08:49:18 volumio volumio[1744]: info: Aug 20 08:49:18 volumio volumio[1744]: ---------------------------- MPD announces state update: player Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::getState Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand status Aug 20 08:49:18 volumio volumio[1744]: info: sending command... Aug 20 08:49:18 volumio volumio[1744]: info: parsing response... Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::parseState Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:49:18 volumio volumio[1744]: info: sending command... Aug 20 08:49:18 volumio volumio[1744]: info: parsing response... Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::parseTrackInfo Aug 20 08:49:18 volumio volumio[1744]: info: ControllerMpd::pushState Aug 20 08:49:18 volumio volumio[1744]: info: CoreCommandRouter::servicePushState Aug 20 08:49:18 volumio volumio[1744]: info: CoreStateMachine::syncState Aug 20 08:49:18 volumio volumio[1744]: info: CorePlayQueue::getTrack 715 Aug 20 08:49:18 volumio volumio[1744]: info: STATE SERVICE {"status":"play","position":0,"seek":469,"duration":259,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"牧马城市-(电视剧《老男孩》片尾曲)","artist":"毛不易","album":null,"uri":"USB/2E10-1B37/单曲/毛不易-牧马城市-(电视剧《老男孩》片尾曲).wav","trackType":"wav"} Aug 20 08:49:18 volumio volumio[1744]: info: CURRENT POSITION 715 Aug 20 08:49:18 volumio volumio[1744]: info: CoreStateMachine::syncState stateService play Aug 20 08:49:18 volumio volumio[1744]: info: CoreStateMachine::syncState currentStatus stop Aug 20 08:49:18 volumio volumio[1744]: info: CoreStateMachine::pushState Aug 20 08:49:18 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:49:18 volumio volumio[1744]: info: CorePlayQueue::getTrack 715 Aug 20 08:49:18 volumio volumio[1744]: info: CoreCommandRouter::volumioPushState Aug 20 08:49:18 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:49:18 volumio volumio[1744]: info: ------------------------------ 93ms Aug 20 08:49:18 volumio sudo[2017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:49:18 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:49:18 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Aug 20 08:49:28 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SHE/f3b3f28f-55c9-42c1-9b9f-1cc11d736d5d.jpg' Aug 20 08:49:30 volumio volumio[1744]: 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' Aug 20 08:49:30 volumio volumio[1744]: 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' Aug 20 08:49:31 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B2%99%E5%AE%9D%E4%BA%AE/ad6d2e97-b7b7-4e21-ad7e-eea869895949.jpg' Aug 20 08:49:31 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B8%B8%E9%B8%BF%E6%98%8E/ce4b8346-7a67-4eb6-a60d-0458c283ea61.jpg' Aug 20 08:49:31 volumio volumio[1744]: 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' Aug 20 08:49:32 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B2%91%E5%AE%81%E5%84%BF/fd318a05-c091-45ea-8a97-699d55c4ea34.jpg' Aug 20 08:49:32 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E4%BF%A1%E5%93%B2/f9650e73-1cf2-47e3-8695-cdf60a1ab167.jpg' Aug 20 08:49:32 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E5%9B%BD%E8%8D%A3/a22f5776-0371-4f6e-bdc7-456ea8531df1.jpg' Aug 20 08:49:32 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E5%AE%87/2fbc7fce-474f-478a-82a3-6309fce205cc.jpg' Aug 20 08:49:32 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%83%A0%E5%A6%B9/47c789f4-b463-40fc-bb33-b6ce8c534b3c.jpg' Aug 20 08:49:35 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%9D%B0/9f13b301-c1a4-4117-b3cd-7b8499db5cb8.jpg' Aug 20 08:49:35 volumio volumio[1744]: 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' Aug 20 08:49:35 volumio volumio[1744]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%8E%89%E5%88%9A/54cea8d4-d69e-4f24-948b-60da100cfdb3.jpg' Aug 20 08:49:35 volumio volumio[1744]: 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' Aug 20 08:49:35 volumio volumio[1744]: 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' Aug 20 08:49:36 volumio volumio[1744]: 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' Aug 20 08:49:36 volumio volumio[1744]: 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' Aug 20 08:49:36 volumio volumio[1744]: 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' Aug 20 08:49:37 volumio volumio[1744]: 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' Aug 20 08:49:38 volumio volumio[1744]: 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' Aug 20 08:49:38 volumio volumio[1744]: 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' Aug 20 08:49:42 volumio volumio[1744]: info: CoreCommandRouter::volumioGetState Aug 20 08:49:42 volumio volumio[1744]: info: CoreStateMachine::getState Aug 20 08:49:42 volumio volumio[1744]: info: CorePlayQueue::getTrack 715 Aug 20 08:49:42 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:49:42 volumio volumio[1744]: info: CoreCommandRouter::volumioGetQueue Aug 20 08:49:42 volumio volumio[1744]: info: CoreStateMachine::getQueue Aug 20 08:49:42 volumio volumio[1744]: info: CorePlayQueue::getQueue Aug 20 08:49:42 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:49:42 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Aug 20 08:49:42 volumio volumio[1744]: info: CoreCommandRouter::volumioGetVisibleSources Aug 20 08:49:42 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 08:49:42 volumio volumio[1744]: info: Listing playlists Aug 20 08:49:42 volumio volumio[1744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 20 08:49:42 volumio sudo[2085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 08:49:42 volumio sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 08:49:42 volumio sudo[2085]: pam_unix(sudo:session): session closed for user root Aug 20 08:49:52 volumio volumio[1744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:49:52 volumio volumio[1744]: { Error: connect ETIMEDOUT 104.244.43.182:80 Aug 20 08:49:52 volumio volumio[1744]: at Object._errnoException (util.js:1022:11) Aug 20 08:49:52 volumio volumio[1744]: at _exceptionWithHostPort (util.js:1044:20) Aug 20 08:49:52 volumio volumio[1744]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 20 08:49:52 volumio volumio[1744]: code: 'ETIMEDOUT', Aug 20 08:49:52 volumio volumio[1744]: errno: 'ETIMEDOUT', Aug 20 08:49:52 volumio volumio[1744]: syscall: 'connect', Aug 20 08:49:52 volumio volumio[1744]: address: '104.244.43.182', Aug 20 08:49:52 volumio volumio[1744]: port: 80 } Aug 20 08:49:52 volumio volumio[1744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:49:53 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 08:48 Aug 20 08:49:53 volumio sudo[2120]: 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"