-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Wed 2025-05-21 21:05:47 +07. -- May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin bluetooth to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin multiroom to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin metavolumio to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin cd_controller to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin qobuzconnect to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin smart_inputs to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: Adding plugin tidalconnect to MyMusic Plugins May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 21 21:04:00 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:00 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:00 volumio9 volumio[3353]: info: Starting MyVolumio Remote Streaming Endpoints May 21 21:04:00 volumio9 volumio[3353]: info: MyVolumio login type: Token May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 21 21:04:00 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 21 21:04:01 volumio9 sudo[3405]: pam_unix(sudo:session): session closed for user root May 21 21:04:01 volumio9 volumio[3353]: info: Starting Streaming Service Transparent Proxy May 21 21:04:01 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 21 21:04:01 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 21 21:04:01 volumio9 volumio[3353]: info: Streaming services startup May 21 21:04:01 volumio9 volumio[3353]: info: Starting Streaming Daemon May 21 21:04:01 volumio9 sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 21:04:01 volumio9 sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:01 volumio9 sudo[3752]: pam_unix(sudo:session): session closed for user root May 21 21:04:01 volumio9 volumio[3353]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 21 21:04:01 volumio9 volumio[3353]: info: Getting Spotify volume May 21 21:04:01 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:01 volumio9 volumio[3353]: error: Cannot start Volumio Streaming Daemon May 21 21:04:01 volumio9 volumio[3353]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 21:04:01 volumio9 volumio[3353]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 21:04:01 volumio9 volumio[3353]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds May 21 21:04:01 volumio9 volumio[3353]: info: Spotify volume: 100 May 21 21:04:01 volumio9 volumio[3353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 21 21:04:01 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetState May 21 21:04:01 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:02 volumio9 volumio[3353]: STREAMING PROXY: Starting server on port 3245 May 21 21:04:02 volumio9 volumio[3353]: Node JS runtime: 14 May 21 21:04:02 volumio9 volumio[3353]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 21 21:04:03 volumio9 volumio[3353]: info: MyVolumio token set successfully May 21 21:04:03 volumio9 volumio[3353]: info: MYVOLUMIO: Adding device May 21 21:04:03 volumio9 volumio[3353]: info: MYVOLUMIO: Evaluating Server May 21 21:04:03 volumio9 volumio[3353]: info: MyVolumio status changed May 21 21:04:03 volumio9 volumio[3353]: info: Streaming services startup May 21 21:04:03 volumio9 volumio[3353]: info: Starting Streaming Daemon May 21 21:04:03 volumio9 volumio[3353]: info: Removing browser output: myVolumio user plan is not superstar May 21 21:04:03 volumio9 volumio[3353]: info: Removing audio output: May 21 21:04:03 volumio9 volumio[3353]: info: Stoppping Tunnel 1 May 21 21:04:03 volumio9 sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 21:04:03 volumio9 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:03 volumio9 sudo[3777]: pam_unix(sudo:session): session closed for user root May 21 21:04:03 volumio9 volumio[3353]: error: Cannot start Volumio Streaming Daemon May 21 21:04:03 volumio9 sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 21 21:04:03 volumio9 volumio[3353]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 21:04:03 volumio9 volumio[3353]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 21:04:03 volumio9 sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:03 volumio9 sudo[3780]: pam_unix(sudo:session): session closed for user root May 21 21:04:03 volumio9 volumio[3353]: info: Remote SSH Stopped May 21 21:04:03 volumio9 volumio[3353]: info: Setting Geolocation for MyVolumio to as1 May 21 21:04:03 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:03 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:03 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:04 volumio9 volumio[3353]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 21 21:04:04 volumio9 volumio[3353]: info: Updating MyVolumio device info May 21 21:04:04 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:04 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:04 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:05 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:05 volumio9 volumio[3353]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 21 21:04:06 volumio9 sudo[3799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.13:/Music /mnt/NAS/Music May 21 21:04:06 volumio9 sudo[3799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:08 volumio9 volumio[3353]: info: MYVOLUMIO: Adding device May 21 21:04:08 volumio9 volumio[3353]: info: MYVOLUMIO: Evaluating Server May 21 21:04:09 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:09 volumio9 volumio[3353]: info: Setting Geolocation for MyVolumio to as1 May 21 21:04:09 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:09 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:09 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:09 volumio9 volumio[3353]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 21 21:04:09 volumio9 volumio[3353]: info: AutoStart - Plugin is starting May 21 21:04:09 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetQueue May 21 21:04:09 volumio9 volumio[3353]: info: CoreStateMachine::getQueue May 21 21:04:09 volumio9 volumio[3353]: info: CorePlayQueue::getQueue May 21 21:04:10 volumio9 volumio[3353]: info: Updating MyVolumio device info May 21 21:04:10 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:10 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:10 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:10 volumio9 volumio[3353]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 21 21:04:13 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:13 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 21 21:04:13 volumio9 volumio[3353]: info: Adding a new share May 21 21:04:13 volumio9 volumio[3353]: info: Checking key NasMounts.9a17e46a-a0bc-44d3-92b9-69e309427589 May 21 21:04:13 volumio9 volumio[3353]: info: No correspondence found in configuration for share Music Server on IP 192.168.1.13 May 21 21:04:13 volumio9 volumio[3353]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.1.13 -N May 21 21:04:13 volumio9 volumio[3353]: info: Negotiated SMB version with Music Server: SMB3_00 May 21 21:04:13 volumio9 volumio[3353]: info: Detected device Music Server with version SMB3_00 May 21 21:04:13 volumio9 volumio[3353]: warn: Could not determine version number from SMB3_00 May 21 21:04:13 volumio9 sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Music /mnt/NAS/Music_Server May 21 21:04:13 volumio9 sudo[3836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:13 volumio9 kernel: CIFS: Attempting to mount //192.168.1.13/Music May 21 21:04:14 volumio9 sudo[3836]: pam_unix(sudo:session): session closed for user root May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: info: May 21 21:04:14 volumio9 volumio[3353]: ---------------------------- MPD announces state update: update May 21 21:04:14 volumio9 volumio[3353]: info: ControllerMpd::getState May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: info: May 21 21:04:14 volumio9 volumio[3353]: ---------------------------- MPD announces state update: update May 21 21:04:14 volumio9 volumio[3353]: info: ControllerMpd::getState May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: info: May 21 21:04:14 volumio9 volumio[3353]: ---------------------------- MPD announces state update: update May 21 21:04:14 volumio9 volumio[3353]: info: ControllerMpd::getState May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::sendMpdCommand status May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 9 milliseconds May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 6 milliseconds May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 6 milliseconds May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 5 milliseconds May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 4 milliseconds May 21 21:04:14 volumio9 volumio[3353]: info: sendMpdCommand status took 3 milliseconds May 21 21:04:14 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: info: Command Router : Notfying DB Updatetrue May 21 21:04:15 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: info: Command Router : Notfying DB Updatetrue May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::Close All Modals sent May 21 21:04:15 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: info: Command Router : Notfying DB Updatetrue May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::Close All Modals sent May 21 21:04:15 volumio9 volumio[3353]: verbose: ControllerMpd::parseState May 21 21:04:15 volumio9 volumio[3353]: info: ControllerMpd::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::servicePushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 21:04:15 volumio9 volumio[3353]: verbose: CURRENT POSITION 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState stateService stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState currentStatus stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: No code May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: ControllerMpd::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::servicePushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 21:04:15 volumio9 volumio[3353]: verbose: CURRENT POSITION 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState stateService stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState currentStatus stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: No code May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: ControllerMpd::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::servicePushState May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 21:04:15 volumio9 volumio[3353]: verbose: CURRENT POSITION 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState stateService stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::syncState currentStatus stop May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: No code May 21 21:04:15 volumio9 volumio[3353]: info: CoreStateMachine::pushState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioPushState May 21 21:04:15 volumio9 volumio[3353]: info: ------------------------------ 1750ms May 21 21:04:15 volumio9 volumio[3353]: info: ------------------------------ 1749ms May 21 21:04:15 volumio9 volumio[3353]: info: ------------------------------ 1748ms May 21 21:04:15 volumio9 volumio[3353]: info: [1747836255845] Database update started May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 21:04:15 volumio9 volumio[3353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 21 21:04:15 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetState May 21 21:04:15 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:17 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:04:20 volumio9 volumio-remote-updater[606]: No test mode May 21 21:04:20 volumio9 volumio-remote-updater[606]: No alpha test mode May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 21 21:04:20 volumio9 volumio[3353]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetBrowseSources May 21 21:04:20 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:21 volumio9 volumio[3353]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 21 21:04:21 volumio9 volumio[3353]: info: Completed starting MyVolumio Plugin May 21 21:04:21 volumio9 volumio[3353]: [Metrics] CommandRouter: 66s 879.16ms May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::volumiosetStartupVolume May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::Close All Modals sent May 21 21:04:21 volumio9 volumio[3353]: info: CoreCommandRouter::Close All Modals sent May 21 21:04:22 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 21 21:04:22 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 21:04:22 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 21 21:04:25 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:26 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:04:26 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:04:26 volumio9 volumio[3353]: info: Discovery: Getting this device information May 21 21:04:26 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetState May 21 21:04:26 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:26 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:04:28 volumio9 volumio[3353]: info: BOOT COMPLETED May 21 21:04:29 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:33 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:37 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:41 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:45 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:49 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:51 volumio9 sudo[3799]: pam_unix(sudo:session): session closed for user root May 21 21:04:51 volumio9 volumio[3353]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds May 21 21:04:52 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 21 21:04:52 volumio9 volumio[3353]: info: Share Music successfully unmounted May 21 21:04:52 volumio9 sudo[3997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.13:Music /mnt/NAS/Music May 21 21:04:52 volumio9 sudo[3997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:53 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:04:56 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:04:56 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:04:56 volumio9 volumio[3353]: info: Discovery: Getting this device information May 21 21:04:56 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetState May 21 21:04:56 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:04:56 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:04:56 volumio9 sudo[4017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.13:Music /mnt/NAS/Music May 21 21:04:56 volumio9 sudo[4017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:04:57 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:01 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:05 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:09 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:13 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:17 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:21 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:25 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:25 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:05:25 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:05:25 volumio9 volumio[3353]: info: Discovery: Getting this device information May 21 21:05:25 volumio9 volumio[3353]: info: CoreCommandRouter::volumioGetState May 21 21:05:25 volumio9 volumio[3353]: info: CorePlayQueue::getTrack 0 May 21 21:05:25 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:05:29 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:33 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:34 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 21 21:05:34 volumio9 volumio[3353]: info: Share Music successfully unmounted May 21 21:05:34 volumio9 sudo[4150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.13:Music /mnt/NAS/Music_server May 21 21:05:34 volumio9 sudo[4150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:05:37 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:37 volumio9 sudo[3997]: pam_unix(sudo:session): session closed for user root May 21 21:05:37 volumio9 volumio[3353]: info: An error occurred mounting the new share. Rolling back configuration May 21 21:05:38 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 21:05:41 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:42 volumio9 sudo[4017]: pam_unix(sudo:session): session closed for user root May 21 21:05:42 volumio9 volumio[3353]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds May 21 21:05:45 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 21:05:45 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 21 21:05:46 volumio9 volumio[3353]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 21:05:47 volumio9 volumio[3353]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:05:47 volumio9 volumio[3353]: TypeError: Cannot read property 'replace' of undefined May 21 21:05:47 volumio9 volumio[3353]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 21 21:05:47 volumio9 volumio[3353]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 21 21:05:47 volumio9 volumio[3353]: at listOnTimeout (internal/timers.js:554:17) May 21 21:05:47 volumio9 volumio[3353]: at processTimers (internal/timers.js:497:7) May 21 21:05:47 volumio9 volumio[3353]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:05:47 volumio9 sudo[4214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 21:04 May 21 21:05:47 volumio9 sudo[4214]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"