-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Mon 2025-12-29 06:03:36 +07. -- Dec 29 06:03:13 chot ntpd[1381]: receive: Unexpected origin timestamp 0xecfc31dc.c26b483c does not match aorg 0000000000.00000000 from server@171.103.24.86 xmt 0xecfc35b1.f6e5dc66 Dec 29 06:03:13 chot ntpd[1381]: receive: Unexpected origin timestamp 0xecfc31dc.c26cae5b does not match aorg 0000000000.00000000 from server@202.28.116.236 xmt 0xecfc35b1.f75b2b34 Dec 29 06:03:13 chot ntpd[1381]: receive: Unexpected origin timestamp 0xecfc31dc.c26dbdde does not match aorg 0000000000.00000000 from server@202.28.92.208 xmt 0xecfc35b1.f7828063 Dec 29 06:03:13 chot ntpd[1381]: receive: Unexpected origin timestamp 0xecfc31dc.c26699c4 does not match aorg 0000000000.00000000 from server@202.28.33.225 xmt 0xecfc35b1.f768114e Dec 29 06:03:14 chot sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Chot11,password=695695,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.1.2/DSD /mnt/NAS/Asus_EEMC Dec 29 06:03:14 chot sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 06:03:14 chot sudo[1567]: pam_unix(sudo:session): session closed for user root Dec 29 06:03:14 chot kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.1.2\DSD Dec 29 06:03:14 chot kernel: CIFS VFS: cifs_mount failed w/return code = -2 Dec 29 06:03:14 chot volumio[883]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 29 06:03:14 chot volumio[883]: info: Cannot mount NAS Asus_EEMC at system boot, trial number 4 ,retrying in 5 seconds Dec 29 06:03:14 chot volumio[883]: STREAMING PROXY: Starting server on port 3245 Dec 29 06:03:14 chot volumio[883]: Node JS runtime: 14 Dec 29 06:03:15 chot volumio[883]: info: MyVolumio login type: Token Dec 29 06:03:15 chot volumio[883]: info: MyVolumio token set successfully Dec 29 06:03:15 chot volumio[883]: info: MYVOLUMIO: Adding device Dec 29 06:03:15 chot volumio[883]: info: MYVOLUMIO: Evaluating Server Dec 29 06:03:15 chot volumio[883]: info: MyVolumio status changed Dec 29 06:03:15 chot volumio[883]: info: Streaming services startup Dec 29 06:03:15 chot volumio[883]: info: Starting Streaming Daemon Dec 29 06:03:15 chot volumio[883]: info: Removing browser output: myVolumio user plan is not superstar Dec 29 06:03:15 chot volumio[883]: info: Removing audio output: Dec 29 06:03:15 chot volumio[883]: info: Stoppping Tunnel 1 Dec 29 06:03:16 chot sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 29 06:03:16 chot sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 06:03:16 chot sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 29 06:03:16 chot sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 06:03:16 chot sudo[1616]: pam_unix(sudo:session): session closed for user root Dec 29 06:03:16 chot volumio[883]: error: Cannot start Volumio Streaming Daemon Dec 29 06:03:16 chot sudo[1617]: pam_unix(sudo:session): session closed for user root Dec 29 06:03:16 chot volumio[883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 29 06:03:16 chot volumio[883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 29 06:03:16 chot volumio[883]: info: Remote SSH Stopped Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::volumioGetState Dec 29 06:03:16 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:16 chot volumio[883]: info: Setting Geolocation for MyVolumio to as1 Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:16 chot volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 06:03:16 chot volumio[883]: info: Received Get System Info Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 06:03:16 chot volumio[883]: info: Discovery: Getting this device information Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::volumioGetState Dec 29 06:03:16 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:16 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 06:03:17 chot volumio[883]: info: Retrieving Cloud Streaming UI Dec 29 06:03:17 chot volumio[883]: info: Getting Tidal Cloud Configuration Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 06:03:17 chot volumio[883]: info: Getting Qobuz Cloud Configuration Dec 29 06:03:17 chot volumio[883]: info: Asking plugin for UI Config Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 06:03:17 chot volumio[883]: info: Getting Spotify Cloud Configuration Dec 29 06:03:17 chot volumio[883]: info: Asking plugin for UI Config Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 06:03:17 chot volumio[883]: info: Saving Spotify Acccount Dec 29 06:03:17 chot volumio[883]: info: Got Tidal Cloud Configuration Dec 29 06:03:17 chot volumio[883]: info: Got it Dec 29 06:03:17 chot volumio[883]: info: Got it Dec 29 06:03:17 chot volumio[883]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 29 06:03:17 chot volumio[883]: info: Updating MyVolumio device info Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 06:03:17 chot volumio[883]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 06:03:17 chot systemd[1]: systemd-fsckd.service: Succeeded. Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 06:03:17 chot volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 29 06:03:17 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 29 06:03:19 chot sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Chot11,password=695695,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.1.2/DSD /mnt/NAS/Asus_EEMC Dec 29 06:03:19 chot sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 06:03:19 chot sudo[1640]: pam_unix(sudo:session): session closed for user root Dec 29 06:03:19 chot kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.1.2\DSD Dec 29 06:03:19 chot kernel: CIFS VFS: cifs_mount failed w/return code = -2 Dec 29 06:03:19 chot volumio[883]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 29 06:03:20 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Dec 29 06:03:20 chot volumio[883]: info: MYVOLUMIO: Adding device Dec 29 06:03:20 chot volumio[883]: info: MYVOLUMIO: Evaluating Server Dec 29 06:03:21 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 06:03:21 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 06:03:21 chot volumio[883]: info: Setting Geolocation for MyVolumio to as1 Dec 29 06:03:21 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:21 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:21 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:21 chot volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 29 06:03:22 chot volumio[883]: info: Updating MyVolumio device info Dec 29 06:03:22 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:22 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:22 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:22 chot volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand update Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand update took 5 milliseconds Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 11 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 7 milliseconds Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: Command Router : Notfying DB Updatetrue Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: 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} Dec 29 06:03:23 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: No code Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: ------------------------------ 63ms Dec 29 06:03:23 chot volumio[883]: info: MPD Database updated - AlbumList cache refreshed Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: database Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 64 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 64 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 62 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 61 milliseconds Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: Command Router : Notfying DB Updatefalse Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: Command Router : Notfying DB Updatefalse Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:23 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: No code Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:23 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: No code Dec 29 06:03:23 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:23 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:23 chot volumio[883]: info: ------------------------------ 129ms Dec 29 06:03:23 chot volumio[883]: info: ------------------------------ 127ms Dec 29 06:03:23 chot volumio[883]: info: MPD Database updated - AlbumList cache refreshed Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: database Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: MPD Database updated - AlbumList cache refreshed Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: database Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: Dec 29 06:03:23 chot volumio[883]: ---------------------------- MPD announces state update: update Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: ControllerMpd::getState Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::sendMpdCommand status Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 86 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 86 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 85 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 17 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 16 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 16 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 15 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 14 milliseconds Dec 29 06:03:23 chot volumio[883]: info: sendMpdCommand status took 14 milliseconds Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:23 chot volumio[883]: info: Command Router : Notfying DB Updatefalse Dec 29 06:03:23 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:23 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:24 chot volumio[883]: info: Command Router : Notfying DB Updatefalse Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:24 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:24 chot volumio[883]: info: Command Router : Notfying DB Updatefalse Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:24 chot volumio[883]: verbose: ControllerMpd::parseState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ControllerMpd::pushState Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::servicePushState Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 06:03:24 chot volumio[883]: verbose: CURRENT POSITION 0 Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState stateService stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::syncState currentStatus stop Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: No code Dec 29 06:03:24 chot volumio[883]: info: CoreStateMachine::pushState Dec 29 06:03:24 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:24 chot volumio[883]: info: CoreCommandRouter::volumioPushState Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 274ms Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 274ms Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 205ms Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 205ms Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 203ms Dec 29 06:03:24 chot volumio[883]: info: ------------------------------ 202ms Dec 29 06:03:25 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 06:03:26 chot volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 06:03:26 chot volumio[883]: info: Received Get System Version Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 06:03:26 chot volumio[883]: info: Received Get System Info Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 06:03:26 chot volumio[883]: info: Discovery: Getting this device information Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::volumioGetState Dec 29 06:03:26 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:26 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 06:03:27 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 06:03:27 chot volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 29 06:03:27 chot volumio[883]: info: CoreCommandRouter::volumioGetState Dec 29 06:03:27 chot volumio[883]: info: CorePlayQueue::getTrack 0 Dec 29 06:03:28 chot volumio[883]: info: Disabling MyMusic plugin upnp Dec 29 06:03:28 chot sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 29 06:03:28 chot sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 06:03:28 chot systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 29 06:03:28 chot systemd[1]: upmpdcli.service: Succeeded. Dec 29 06:03:28 chot systemd[1]: Stopped UPnP Renderer front-end to MPD. Dec 29 06:03:28 chot sudo[1705]: pam_unix(sudo:session): session closed for user root Dec 29 06:03:28 chot volumio[883]: info: Disabling plugin upnp Dec 29 06:03:28 chot volumio[883]: info: Done. Dec 29 06:03:29 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 06:03:29 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 06:03:29 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:29 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 06:03:30 chot volumio-remote-updater[555]: No test mode Dec 29 06:03:30 chot volumio-remote-updater[555]: No alpha test mode Dec 29 06:03:30 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 29 06:03:30 chot volumio[883]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 29 06:03:30 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 06:03:32 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 06:03:33 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 06:03:33 chot volumio[883]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 29 06:03:33 chot volumio[883]: info: Completed starting MyVolumio Plugin Dec 29 06:03:33 chot volumio[883]: [Metrics] CommandRouter: 40s 290.54ms Dec 29 06:03:33 chot volumio[883]: info: CoreCommandRouter::volumiosetStartupVolume Dec 29 06:03:33 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 06:03:33 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 06:03:33 chot volumio[883]: info: CoreCommandRouter::Close All Modals sent Dec 29 06:03:34 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 29 06:03:34 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 29 06:03:34 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 29 06:03:36 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 29 06:03:36 chot volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 29 06:03:36 chot volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 06:03:36 chot volumio[883]: Error: getaddrinfo -3007 Dec 29 06:03:36 chot volumio[883]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Dec 29 06:03:36 chot volumio[883]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Dec 29 06:03:36 chot volumio[883]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Dec 29 06:03:36 chot volumio[883]: code: -3007, Dec 29 06:03:36 chot volumio[883]: errno: -3007, Dec 29 06:03:36 chot volumio[883]: syscall: 'getaddrinfo' Dec 29 06:03:36 chot volumio[883]: } Dec 29 06:03:36 chot volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 06:03:36 chot sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 06:02 Dec 29 06:03:36 chot sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"