-- Logs begin at Sun 2020-08-09 05:44:21 UTC, end at Sun 2025-02-16 08:49:10 UTC. -- Feb 16 08:48:01 volumio volumio[1138]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 08:48:03 volumio volumio[1138]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E8%95%99/eabd4649-f466-44be-9071-bcc88e968b94.jpg' Feb 16 08:48:23 volumio volumio[1138]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 08:48:23 volumio volumio[1138]: { Error: connect ETIMEDOUT 31.13.80.37:443 Feb 16 08:48:23 volumio volumio[1138]: at Object._errnoException (util.js:1022:11) Feb 16 08:48:23 volumio volumio[1138]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 08:48:23 volumio volumio[1138]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 08:48:23 volumio volumio[1138]: code: 'ETIMEDOUT', Feb 16 08:48:23 volumio volumio[1138]: errno: 'ETIMEDOUT', Feb 16 08:48:23 volumio volumio[1138]: syscall: 'connect', Feb 16 08:48:23 volumio volumio[1138]: address: '31.13.80.37', Feb 16 08:48:23 volumio volumio[1138]: port: 443 } Feb 16 08:48:23 volumio volumio[1138]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 08:48:24 volumio sudo[22949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 08:47 Feb 16 08:48:24 volumio sudo[22949]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:24 volumio sudo[22949]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:25 volumio volumio-remote-updater[583]: [2025-02-16 08:48:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 08:48:25 volumio volumio-remote-updater[583]: [2025-02-16 08:48:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 08:48:25 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 16 08:48:25 volumio systemd[1]: Unit volumio.service entered failed state. Feb 16 08:48:25 volumio systemd[1]: Starting dynamicswap service... Feb 16 08:48:25 volumio systemd[1]: Started dynamicswap service. Feb 16 08:48:25 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 16 08:48:25 volumio systemd[1]: Starting dynamicswap service... Feb 16 08:48:25 volumio systemd[1]: Started dynamicswap service. Feb 16 08:48:25 volumio systemd[1]: Stopping Volumio Backend Module... Feb 16 08:48:25 volumio systemd[1]: Starting Volumio Backend Module... Feb 16 08:48:25 volumio systemd[1]: Started Volumio Backend Module. Feb 16 08:48:27 volumio kernel: Under-voltage detected! (0x00050005) Feb 16 08:48:28 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:28 volumio volumio[22974]: info: ----- Volumio2 ---- Feb 16 08:48:28 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:28 volumio volumio[22974]: info: ----- System startup ---- Feb 16 08:48:28 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:28 volumio volumio[22974]: info: MYVOLUMIO Environment detected Feb 16 08:48:29 volumio volumio[22974]: info: Plugin folders cleanup Feb 16 08:48:29 volumio volumio[22974]: info: Scanning into folder /volumio/app/plugins/ Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category audio_interface Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category miscellanea Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category music_service Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category plugins.json Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category system_controller Feb 16 08:48:29 volumio volumio[22974]: info: Scanning category user_interface Feb 16 08:48:29 volumio volumio[22974]: info: Scanning into folder /data/plugins/ Feb 16 08:48:29 volumio volumio[22974]: info: Plugin folders cleanup completed Feb 16 08:48:29 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:29 volumio volumio[22974]: info: ----- Core plugins startup ---- Feb 16 08:48:29 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 08:48:29 volumio volumio[22974]: info: Adding plugin upnp to MyMusic Plugins Feb 16 08:48:29 volumio volumio[22974]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 08:48:29 volumio volumio[22974]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugins from folder /data/plugins/ Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "system"... Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "appearance"... Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "network"... Feb 16 08:48:29 volumio volumio[22974]: info: Refreshing Cached IP Addresses Feb 16 08:48:29 volumio sudo[23001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "services"... Feb 16 08:48:29 volumio sudo[23001]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "alsa_controller"... Feb 16 08:48:29 volumio sudo[23004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 08:48:29 volumio sudo[23001]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:29 volumio sudo[23004]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:29 volumio sudo[23004]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "wizard"... Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "volumio_command_line_client"... Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "upnp"... Feb 16 08:48:29 volumio volumio[22974]: info: [1739695709793] Starting Upmpd Daemon Feb 16 08:48:29 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "my_music"... Feb 16 08:48:29 volumio volumio[22974]: info: Loading plugin "mpd"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "upnp_browser"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "networkfs"... Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "alarm-clock"... Feb 16 08:48:30 volumio sudo[23025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\AMSR /mnt/NAS/MY-AMSR Feb 16 08:48:30 volumio sudo[23025]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:30 volumio sudo[23027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\歌曲 /mnt/NAS/MY-Music Feb 16 08:48:30 volumio sudo[23027]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:30 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:48:30 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "airplay_emulation"... Feb 16 08:48:30 volumio volumio[22974]: info: Starting Shairport Sync Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "last_100"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "webradio"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "i2s_dacs"... Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "volumiodiscovery"... Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 08:48:30 volumio node[22974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 08:48:30 volumio node[22974]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 08:48:30 volumio node[22974]: *** WARNING *** For more information see Feb 16 08:48:30 volumio node[22974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 08:48:30 volumio node[22974]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 08:48:30 volumio node[22974]: *** WARNING *** For more information see Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** For more information see Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 08:48:30 volumio volumio[22974]: *** WARNING *** For more information see Feb 16 08:48:30 volumio volumio[22974]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 08:48:30 volumio volumio[22974]: Discovery: StartAdv! undefined Feb 16 08:48:30 volumio volumio[22974]: Discovery: Started advertising... Volumio - undefined Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "outputs"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "albumart"... Feb 16 08:48:30 volumio volumio[22974]: info: Plugin example_plugin is not enabled Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "inputs"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "updater_comm"... Feb 16 08:48:30 volumio volumio-remote-updater[583]: [2025-02-16 08:48:30] [connect] Successful connection Feb 16 08:48:30 volumio volumio[22974]: info: Plugin mpdemulation is not enabled Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "rest_api"... Feb 16 08:48:30 volumio volumio[22974]: info: Loading plugin "websocket"... Feb 16 08:48:30 volumio volumio[22974]: info: ___________ START PLUGINS ___________ Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 08:48:30 volumio volumio[22974]: info: [1739695710717] CoreMusicLibrary::Adding element Media Servers Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:30 volumio volumio[22974]: Forking 3 albumart workers Feb 16 08:48:30 volumio volumio[22974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 08:48:30 volumio volumio[22974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 08:48:30 volumio volumio[22974]: info: [1739695710804] CoreMusicLibrary::Adding element Last_100 Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 08:48:30 volumio volumio[22974]: info: [1739695710807] CoreMusicLibrary::Adding element Webradio Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 08:48:30 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:30 volumio volumio[22974]: info: ----- MyVolumio plugins startup ---- Feb 16 08:48:30 volumio volumio[22974]: info: ------------------------------------------- Feb 16 08:48:30 volumio volumio[22974]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 08:48:30 volumio volumio[22974]: info: Loading i18n strings for locale zh Feb 16 08:48:30 volumio volumio[22974]: Updating browse sources language Feb 16 08:48:30 volumio volumio[22974]: Cannot find translation for sourceMedia Servers Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::initPlayerControls Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 08:48:30 volumio volumio[22974]: info: BOOT COMPLETED Feb 16 08:48:30 volumio volumio[22974]: [Metrics] CommandRouter: 2s 870.22ms Feb 16 08:48:30 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:48:31 volumio volumio[22974]: aplay: main:722: audio open error: Device or resource busy Feb 16 08:48:31 volumio volumio[22974]: Cannot play startup sound Feb 16 08:48:31 volumio volumio[22974]: info: CoreCommandRouter::Close All Modals sent Feb 16 08:48:31 volumio volumio[22974]: info: CoreCommandRouter::Close All Modals sent Feb 16 08:48:31 volumio volumio[22974]: Express server listening on port 3000 Feb 16 08:48:31 volumio volumio[22974]: [Metrics] WebUI: 3s 673.94ms Feb 16 08:48:31 volumio volumio[22974]: Volumio Calling Home Feb 16 08:48:31 volumio volumio[22974]: info: CoreStateMachine::resetVolumioState Feb 16 08:48:31 volumio volumio[22974]: info: CoreStateMachine::getcurrentVolume Feb 16 08:48:31 volumio volumio[22974]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 08:48:31 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:48:31 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:31 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:48:31 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:48:31 volumio volumio[22974]: info: Setting Device type: Raspberry PI Feb 16 08:48:31 volumio volumio[22974]: info: MPD running with PID652 Feb 16 08:48:31 volumio volumio[22974]: ,establishing connection Feb 16 08:48:31 volumio volumio-remote-updater[583]: [2025-02-16 08:48:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1739695710 101 Feb 16 08:48:31 volumio volumio[22974]: Starting albumart workers Feb 16 08:48:31 volumio volumio[22974]: Starting albumart workers Feb 16 08:48:31 volumio volumio[22974]: Starting albumart workers Feb 16 08:48:32 volumio volumio[22974]: info: Reloading queue from file Feb 16 08:48:32 volumio volumio[22974]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 16 08:48:32 volumio volumio[22974]: info: CoreStateMachine::setRepeat false single undefined Feb 16 08:48:32 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:48:32 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:48:32 volumio volumio[22974]: info: CoreStateMachine::setRandom false Feb 16 08:48:32 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:48:32 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:48:32 volumio volumio[22974]: info: Starting Shairport Sync Feb 16 08:48:32 volumio volumio[22974]: info: Starting Shairport Sync Feb 16 08:48:32 volumio sudo[23086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 08:48:32 volumio sudo[23087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 08:48:32 volumio sudo[23086]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:32 volumio sudo[23087]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 08:48:32 volumio volumio[22974]: Discovery: adding c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 08:48:32 volumio volumio[22974]: info: mDNS: Found device Volumio Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::volumioGetState Feb 16 08:48:32 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:32 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 08:48:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 08:48:32 volumio sudo[23086]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:32 volumio sudo[23087]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:32 volumio volumio[22974]: Discovery: this is already registered, c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 08:48:32 volumio volumio[22974]: info: mDNS: Found device Volumio Feb 16 08:48:32 volumio volumio[22974]: info: CoreCommandRouter::volumioGetState Feb 16 08:48:32 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:32 volumio volumio[22974]: info: Shairport-Sync Started Feb 16 08:48:32 volumio volumio[22974]: Error adding Membership: Error: addMembership EINVAL Feb 16 08:48:32 volumio volumio[22974]: info: Shairport-Sync Started Feb 16 08:48:33 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::volumioGetState Feb 16 08:48:33 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:33 volumio volumio[22974]: info: Listing playlists Feb 16 08:48:33 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B1%A0%E6%B4%AA%E5%88%9A/%E7%B2%BE%E5%BF%A0%E6%8A%A5%E5%9B%BD/bd605a7f-4068-42d4-9e07-9f3c4ff5e159.png' Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::volumioGetQueue Feb 16 08:48:33 volumio volumio[22974]: info: CoreStateMachine::getQueue Feb 16 08:48:33 volumio volumio[22974]: info: CorePlayQueue::getQueue Feb 16 08:48:33 volumio volumio[22974]: info: CoreCommandRouter::volumioGetState Feb 16 08:48:33 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:48:34 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E4%B8%80%E4%B8%87%E4%B8%AA%E8%88%8D%E4%B8%8D%E5%BE%97/d02c1463-0c20-4d71-b7ce-8f760983ce4d.png' Feb 16 08:48:34 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:34 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:35 volumio volumio[22974]: 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/%E6%9C%80%E7%BA%A2/71e5e1d0-e8e0-4b45-a30d-3db55934b407.png' Feb 16 08:48:35 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:48:35 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 08:48:37 volumio kernel: Voltage normalised (0x00000000) Feb 16 08:48:38 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:38 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:39 volumio sudo[23154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 08:48:39 volumio sudo[23154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:39 volumio sudo[23154]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:39 volumio sudo[23157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 08:48:39 volumio sudo[23157]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:39 volumio sudo[23165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 08:48:39 volumio sudo[23157]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:39 volumio sudo[23165]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:39 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 08:48:39 volumio sudo[23165]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:39 volumio volumio[22974]: info: Upmpdcli Daemon Started Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 16 08:48:40 volumio volumio[22974]: info: Adding plugin bluetooth to MyMusic Plugins Feb 16 08:48:40 volumio volumio[22974]: info: Adding plugin cd_controller to MyMusic Plugins Feb 16 08:48:40 volumio volumio[22974]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 16 08:48:40 volumio volumio[22974]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 16 08:48:40 volumio sudo[23027]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:40 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Feb 16 08:48:40 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -115 Feb 16 08:48:40 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Feb 16 08:48:40 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -115 Feb 16 08:48:40 volumio sudo[23025]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 16 08:48:41 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:48:41 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:48:41 volumio volumio[22974]: info: Starting MyVolumio Remote Streaming Endpoints Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 16 08:48:41 volumio volumio[22974]: info: Streaming services startup Feb 16 08:48:41 volumio volumio[22974]: info: Starting Streaming Daemon Feb 16 08:48:41 volumio volumio[22974]: info: MyVolumio not started Feb 16 08:48:41 volumio volumio[22974]: info: Initializing device activation check Feb 16 08:48:41 volumio sudo[23187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 08:48:41 volumio sudo[23187]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:41 volumio volumio[22974]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 16 08:48:41 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 16 08:48:41 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 16 08:48:41 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 16 08:48:41 volumio sudo[23187]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:41 volumio volumio-streaming-daemon[23193]: ############################ Feb 16 08:48:41 volumio volumio-streaming-daemon[23193]: # Volumio Streaming Daemon # Feb 16 08:48:41 volumio volumio-streaming-daemon[23193]: # Running on port 7777 # Feb 16 08:48:41 volumio volumio-streaming-daemon[23193]: ############################ Feb 16 08:48:41 volumio volumio[22974]: info: Cannot mount NAS MY-AMSR at system boot, trial number 1 ,retrying in 5 seconds Feb 16 08:48:41 volumio volumio-streaming-daemon[23193]: Environment: production Feb 16 08:48:41 volumio volumio[22974]: info: Cannot mount NAS MY-Music at system boot, trial number 1 ,retrying in 5 seconds Feb 16 08:48:41 volumio volumio[22974]: 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/%E7%BB%88%E4%BA%8E%E7%AD%89%E5%88%B0%E4%BD%A0/178d1a05-a33e-4157-9f89-be650320f350.png' Feb 16 08:48:41 volumio volumio[22974]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 08:48:41 volumio volumio[22974]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 08:48:42 volumio volumio[22974]: Dload Upload Total Spent Left Speed Feb 16 08:48:42 volumio volumio[22974]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 08:48:42 volumio volumio[22974]: retrying in 5 seconds, trial 0 Feb 16 08:48:42 volumio volumio[22974]: Volumio Calling Home Feb 16 08:48:44 volumio volumio-streaming-daemon[23193]: Environment: production Feb 16 08:48:44 volumio volumio[22974]: info: Fetching Streaming Services browse cache Feb 16 08:48:44 volumio volumio[22974]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 16 08:48:44 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:44 volumio volumio[22974]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 16 08:48:44 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 08:48:44 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:45 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:45 volumio volumio[22974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 08:48:46 volumio sudo[23218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\AMSR /mnt/NAS/MY-AMSR Feb 16 08:48:46 volumio sudo[23218]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:46 volumio sudo[23221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\歌曲 /mnt/NAS/MY-Music Feb 16 08:48:46 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:48:46 volumio sudo[23221]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:48:46 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:48:49 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 08:48:52 volumio volumio[22974]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 08:48:52 volumio volumio[22974]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 08:48:52 volumio volumio[22974]: Dload Upload Total Spent Left Speed Feb 16 08:48:52 volumio volumio[22974]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 08:48:52 volumio volumio[22974]: retrying in 5 seconds, trial 1 Feb 16 08:48:52 volumio volumio[22974]: Volumio Calling Home Feb 16 08:48:53 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 08:48:54 volumio volumio[22974]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 08:48:54 volumio volumio[22974]: info: CoreStateMachine::ClearQueue Feb 16 08:48:54 volumio volumio[22974]: info: CoreStateMachine::stop Feb 16 08:48:54 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:48:54 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:48:54 volumio volumio[22974]: info: CorePlayQueue::clearPlayQueue Feb 16 08:48:54 volumio volumio[22974]: info: CorePlayQueue::saveQueue Feb 16 08:48:54 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:48:54 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:48:54 volumio volumio[22974]: info: CoreCommandRouter::volumioPushQueue Feb 16 08:48:54 volumio volumio[22974]: info: CoreStateMachine::addQueueItems Feb 16 08:48:54 volumio volumio[22974]: info: CorePlayQueue::addQueueItems Feb 16 08:48:54 volumio volumio[22974]: info: Adding Item to queue: upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$23$0 Feb 16 08:48:54 volumio volumio[22974]: info: Exploding uri upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$23$0 in service upnp_browser Feb 16 08:48:54 volumio volumio[22974]: info: Adding Item to queue: upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$23$1 Feb 16 08:48:54 volumio volumio[22974]: info: Exploding uri upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$23$1 in service upnp_browser Feb 16 08:48:54 volumio volumio[22974]: info: CorePlayQueue::saveQueue Feb 16 08:48:54 volumio volumio[22974]: info: CoreCommandRouter::volumioPushQueue Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::updateTrackBlock Feb 16 08:48:55 volumio volumio[22974]: info: CorePlayQueue::getTrackBlock Feb 16 08:48:55 volumio volumio[22974]: info: CoreCommandRouter::volumioPlay Feb 16 08:48:55 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::play index 1 Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::stop Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:48:55 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::play index undefined Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:48:55 volumio volumio[22974]: info: CorePlayQueue::getTrack 1 Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::startPlaybackTimer Feb 16 08:48:55 volumio volumio[22974]: info: CorePlayQueue::getTrack 1 Feb 16 08:48:55 volumio volumio[22974]: info: [1739695735009] ControllerUPNPBrowser::clearAddPlayTrack Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 08:48:55 volumio volumio[22974]: info: Feb 16 08:48:55 volumio volumio[22974]: ---------------------------- MPD announces state update: player Feb 16 08:48:55 volumio volumio[22974]: info: ControllerMpd::getState Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand status Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 08:48:55 volumio volumio[22974]: info: Feb 16 08:48:55 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:48:55 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::parseState Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.71.11:8200/MediaItems/324.flac" Feb 16 08:48:55 volumio volumio[22974]: error: updateQueue error: null Feb 16 08:48:55 volumio volumio[22974]: info: ------------------------------ 5ms Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::parseTrackInfo Feb 16 08:48:55 volumio volumio[22974]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 16 08:48:55 volumio volumio[22974]: info: ------------------------------ 11ms Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.71.11:8200/MediaItems/324.flac" Feb 16 08:48:55 volumio volumio[22974]: info: Feb 16 08:48:55 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:48:55 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand play Feb 16 08:48:55 volumio volumio[22974]: info: ------------------------------ 2ms Feb 16 08:48:55 volumio volumio[22974]: info: Feb 16 08:48:55 volumio volumio[22974]: ---------------------------- MPD announces state update: player Feb 16 08:48:55 volumio volumio[22974]: info: ControllerMpd::getState Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand status Feb 16 08:48:55 volumio volumio[22974]: info: Feb 16 08:48:55 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:48:55 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::parseState Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 08:48:55 volumio volumio[22974]: info: ------------------------------ 2ms Feb 16 08:48:55 volumio volumio[22974]: verbose: ControllerMpd::parseTrackInfo Feb 16 08:48:55 volumio volumio[22974]: info: ControllerMpd::pushState Feb 16 08:48:55 volumio volumio[22974]: info: CoreCommandRouter::servicePushState Feb 16 08:48:55 volumio volumio[22974]: info: CorePlayQueue::getTrack 1 Feb 16 08:48:55 volumio volumio[22974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"心火","artist":"姚贝娜","album":"1/2的我-EP","uri":"http://192.168.71.11:8200/MediaItems/324.flac","trackType":"flac"} Feb 16 08:48:55 volumio volumio[22974]: verbose: CURRENT POSITION 1 Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::syncState stateService play Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::syncState currentStatus stop Feb 16 08:48:55 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:48:55 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:48:55 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:48:55 volumio volumio[22974]: info: ------------------------------ 14ms Feb 16 08:48:56 volumio sudo[23218]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:56 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Feb 16 08:48:56 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -115 Feb 16 08:48:56 volumio volumio[22974]: info: Cannot mount NAS MY-AMSR at system boot, trial number 2 ,retrying in 5 seconds Feb 16 08:48:56 volumio sudo[23221]: pam_unix(sudo:session): session closed for user root Feb 16 08:48:56 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Feb 16 08:48:56 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -115 Feb 16 08:48:56 volumio volumio[22974]: info: Cannot mount NAS MY-Music at system boot, trial number 2 ,retrying in 5 seconds Feb 16 08:49:00 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 08:49:00 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 08:49:01 volumio sudo[23276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\AMSR /mnt/NAS/MY-AMSR Feb 16 08:49:01 volumio sudo[23276]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:49:01 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:49:01 volumio sudo[23284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Saber,password=Liujiajun1126,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.199.168/Music\歌曲 /mnt/NAS/MY-Music Feb 16 08:49:01 volumio sudo[23284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 08:49:01 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 16 08:49:02 volumio volumio[22974]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 08:49:02 volumio volumio[22974]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 08:49:02 volumio volumio[22974]: Dload Upload Total Spent Left Speed Feb 16 08:49:02 volumio volumio[22974]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 08:49:02 volumio volumio[22974]: retrying in 5 seconds, trial 2 Feb 16 08:49:02 volumio volumio[22974]: Volumio Calling Home Feb 16 08:49:06 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 08:49:07 volumio wpa_supplicant[998]: wlan0: WNM: Disassociation Imminent - Disassociation Timer 30 Feb 16 08:49:07 volumio wpa_supplicant[998]: wlan0: WNM: Preferred List Available Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::ClearQueue Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::stop Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:49:07 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::stPlaybackTimer Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::updateTrackBlock Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrackBlock Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 1 Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::serviceStop Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 1 Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::serviceStop Feb 16 08:49:07 volumio volumio[22974]: info: [1739695747787] ControllerUPNPBrowser::stop Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::clearPlayQueue Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::saveQueue Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPushQueue Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::addQueueItems Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::addQueueItems Feb 16 08:49:07 volumio volumio[22974]: info: Adding Item to queue: upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$A6$0 Feb 16 08:49:07 volumio volumio[22974]: info: Exploding uri upnp/http://192.168.71.11:8200/ctl/ContentDir@1$7$A6$0 in service upnp_browser Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::saveQueue Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPushQueue Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::updateTrackBlock Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrackBlock Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPlay Feb 16 08:49:07 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::play index 0 Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::stop Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:49:07 volumio volumio[22974]: UNSET VOLATILE Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::play index undefined Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::startPlaybackTimer Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:49:07 volumio volumio[22974]: info: [1739695747830] ControllerUPNPBrowser::clearAddPlayTrack Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces state update: player Feb 16 08:49:07 volumio volumio[22974]: info: ControllerMpd::getState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand status Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:49:07 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.71.11:8200/MediaItems/965.flac" Feb 16 08:49:07 volumio volumio[22974]: error: updateQueue error: null Feb 16 08:49:07 volumio volumio[22974]: info: ------------------------------ 5ms Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseTrackInfo Feb 16 08:49:07 volumio volumio[22974]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 16 08:49:07 volumio volumio[22974]: info: ------------------------------ 11ms Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.71.11:8200/MediaItems/965.flac" Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:49:07 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand play Feb 16 08:49:07 volumio volumio[22974]: info: ------------------------------ 5ms Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces state update: player Feb 16 08:49:07 volumio volumio[22974]: info: ControllerMpd::getState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand status Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces system playlist update Feb 16 08:49:07 volumio volumio[22974]: info: Ignoring MPD Status Update Feb 16 08:49:07 volumio volumio[22974]: info: Feb 16 08:49:07 volumio volumio[22974]: ---------------------------- MPD announces state update: player Feb 16 08:49:07 volumio volumio[22974]: info: ControllerMpd::getState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand status Feb 16 08:49:07 volumio volumio[22974]: info: ------------------------------ 5ms Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseState Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseTrackInfo Feb 16 08:49:07 volumio volumio[22974]: verbose: ControllerMpd::parseTrackInfo Feb 16 08:49:07 volumio volumio[22974]: info: ControllerMpd::pushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::servicePushState Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:49:07 volumio volumio[22974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2516 Kbps","isStreaming":false,"title":"I beg you","artist":"Aimer","album":"日语","uri":"http://192.168.71.11:8200/MediaItems/965.flac","trackType":"flac"} Feb 16 08:49:07 volumio volumio[22974]: verbose: CURRENT POSITION 0 Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::syncState stateService play Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::syncState currentStatus stop Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:49:07 volumio volumio[22974]: info: ControllerMpd::pushState Feb 16 08:49:07 volumio volumio[22974]: info: CoreCommandRouter::servicePushState Feb 16 08:49:07 volumio volumio[22974]: info: CorePlayQueue::getTrack 0 Feb 16 08:49:07 volumio volumio[22974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2760 Kbps","isStreaming":false,"title":"I beg you","artist":"Aimer","album":"日语","uri":"http://192.168.71.11:8200/MediaItems/965.flac","trackType":"flac"} Feb 16 08:49:07 volumio volumio[22974]: verbose: CURRENT POSITION 0 Feb 16 08:49:07 volumio volumio[22974]: info: CoreStateMachine::syncState stateService play Feb 16 08:49:08 volumio volumio[22974]: info: CoreStateMachine::syncState currentStatus play Feb 16 08:49:08 volumio volumio[22974]: info: Received an update from plugin. extracting info from payload Feb 16 08:49:08 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:49:08 volumio volumio[22974]: info: CoreStateMachine::pushState Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::volumioPushState Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 08:49:08 volumio volumio[22974]: info: ------------------------------ 45ms Feb 16 08:49:08 volumio volumio[22974]: info: ------------------------------ 43ms Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::volumioGetQueue Feb 16 08:49:08 volumio volumio[22974]: info: CoreStateMachine::getQueue Feb 16 08:49:08 volumio volumio[22974]: info: CorePlayQueue::getQueue Feb 16 08:49:08 volumio volumio[22974]: info: CoreCommandRouter::volumioGetQueue Feb 16 08:49:08 volumio volumio[22974]: info: CoreStateMachine::getQueue Feb 16 08:49:08 volumio volumio[22974]: info: CorePlayQueue::getQueue Feb 16 08:49:08 volumio kernel: brcmfmac: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Feb 16 08:49:09 volumio volumio[22974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 08:49:09 volumio volumio[22974]: { Error: connect ETIMEDOUT 31.13.85.34:80 Feb 16 08:49:09 volumio volumio[22974]: at Object._errnoException (util.js:1022:11) Feb 16 08:49:09 volumio volumio[22974]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 08:49:09 volumio volumio[22974]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 08:49:09 volumio volumio[22974]: code: 'ETIMEDOUT', Feb 16 08:49:09 volumio volumio[22974]: errno: 'ETIMEDOUT', Feb 16 08:49:09 volumio volumio[22974]: syscall: 'connect', Feb 16 08:49:09 volumio volumio[22974]: address: '31.13.85.34', Feb 16 08:49:09 volumio volumio[22974]: port: 80 } Feb 16 08:49:09 volumio volumio[22974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 08:49:10 volumio sudo[23335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 08:48 Feb 16 08:49:10 volumio sudo[23335]: 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="ceaca221623bd3cfdc448100963369088321c5bb" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"