-- Logs begin at Sat 2024-11-23 16:54:47 +07, end at Sat 2024-11-23 17:03:34 +07. -- Nov 23 17:02:00 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 17:02:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:09 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:25 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:29 volumio ntpd[1550]: Soliciting pool server 2404:fbc0:0:2046::c Nov 23 17:02:29 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Nov 23 17:02:29 volumio volumio[973]: info: Share nasfake successfully unmounted Nov 23 17:02:29 volumio sudo[3565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=25tkx,password=Uyen()1203,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.252/volume4\D1.5T /mnt/NAS/nasfake Nov 23 17:02:29 volumio sudo[3565]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:02:29 volumio kernel: CIFS: Attempting to mount //192.168.68.252/volume4/D1.5T Nov 23 17:02:29 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:29 volumio kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.68.252\volume4 Nov 23 17:02:29 volumio sudo[3565]: pam_unix(sudo:session): session closed for user root Nov 23 17:02:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Nov 23 17:02:29 volumio volumio[973]: info: An error occurred mounting the new share. Rolling back configuration Nov 23 17:02:30 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 17:02:33 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:37 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:41 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:45 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:49 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:53 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:02:57 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Nov 23 17:03:03 volumio volumio[973]: info: Share nasfake successfully unmounted Nov 23 17:03:03 volumio sudo[3634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=25tkx,password=Uyen()1203,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.252/D1.5T /mnt/NAS/nasfake Nov 23 17:03:03 volumio sudo[3634]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:03:03 volumio kernel: CIFS: Attempting to mount //192.168.68.252/D1.5T Nov 23 17:03:03 volumio kernel: CIFS: VFS: Autodisabling the use of server inode numbers on new server Nov 23 17:03:03 volumio kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Nov 23 17:03:03 volumio kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Nov 23 17:03:03 volumio sudo[3634]: pam_unix(sudo:session): session closed for user root Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Nov 23 17:03:03 volumio volumio[973]: info: Nov 23 17:03:03 volumio volumio[973]: ---------------------------- MPD announces state update: update Nov 23 17:03:03 volumio volumio[973]: info: ControllerMpd::getState Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Nov 23 17:03:03 volumio volumio[973]: info: Nov 23 17:03:03 volumio volumio[973]: ---------------------------- MPD announces state update: update Nov 23 17:03:03 volumio volumio[973]: info: ControllerMpd::getState Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Nov 23 17:03:03 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Nov 23 17:03:03 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Nov 23 17:03:03 volumio volumio[973]: info: sendMpdCommand status took 4 milliseconds Nov 23 17:03:03 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::parseState Nov 23 17:03:03 volumio volumio[973]: info: Command Router : Notfying DB Updatetrue Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::parseState Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::parseState Nov 23 17:03:03 volumio volumio[973]: info: Command Router : Notfying DB Updatetrue Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::Close All Modals sent Nov 23 17:03:03 volumio volumio[973]: verbose: ControllerMpd::parseState Nov 23 17:03:03 volumio volumio[973]: info: ControllerMpd::pushState Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::servicePushState Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: 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} Nov 23 17:03:03 volumio volumio[973]: verbose: CURRENT POSITION 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::syncState stateService stop Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: No code Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: ControllerMpd::pushState Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::servicePushState Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: 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} Nov 23 17:03:03 volumio volumio[973]: verbose: CURRENT POSITION 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::syncState stateService stop Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: No code Nov 23 17:03:03 volumio volumio[973]: info: CoreStateMachine::pushState Nov 23 17:03:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 23 17:03:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Nov 23 17:03:03 volumio volumio[973]: info: ------------------------------ 204ms Nov 23 17:03:03 volumio volumio[973]: info: ------------------------------ 203ms Nov 23 17:03:03 volumio volumio[973]: info: [1732356183983] Database update started Nov 23 17:03:04 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 17:03:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:09 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:18 volumio volumio[973]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=5eca0c27521216e02c55ff3b0e1abbc2" http://updates.volumio.org/downloader-v1/track-device Nov 23 17:03:18 volumio volumio[973]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 23 17:03:18 volumio volumio[973]: Dload Upload Total Spent Left Speed Nov 23 17:03:18 volumio volumio[973]: [10.3K blob data] Nov 23 17:03:18 volumio volumio[973]: curl: (28) Failed to connect to updates.volumio.org port 80: Connection timed out Nov 23 17:03:18 volumio volumio[973]: retrying in 5 seconds, trial 2 Nov 23 17:03:18 volumio volumio[973]: info: Volumio Calling Home Nov 23 17:03:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:23 volumio volumio[973]: info: Disabling MyMusic plugin upnp Nov 23 17:03:23 volumio sudo[3746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 23 17:03:23 volumio sudo[3746]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:03:23 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 23 17:03:23 volumio volumio[973]: error: Upnp client error: Error: This socket has been ended by the other party Nov 23 17:03:24 volumio volumio[973]: info: Disabling MyMusic plugin airplay_emulation Nov 23 17:03:24 volumio volumio[973]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Nov 23 17:03:24 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 17:03:24 volumio volumio[973]: info: Disabling plugin airplay_emulation Nov 23 17:03:24 volumio volumio[973]: info: Done. Nov 23 17:03:24 volumio sudo[3749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Nov 23 17:03:24 volumio sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:03:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 23 17:03:24 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 23 17:03:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 23 17:03:24 volumio sudo[3749]: pam_unix(sudo:session): session closed for user root Nov 23 17:03:24 volumio volumio[973]: info: Shairport-Sync Stopped Nov 23 17:03:25 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:26 volumio volumio[973]: info: Disabling MyMusic plugin upnp Nov 23 17:03:26 volumio sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 23 17:03:26 volumio sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:03:29 volumio volumio[973]: info: Disabling MyMusic plugin upnp_browser Nov 23 17:03:29 volumio volumio[973]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Nov 23 17:03:29 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 17:03:29 volumio volumio[973]: info: Disabling plugin upnp_browser Nov 23 17:03:29 volumio volumio[973]: info: Done. Nov 23 17:03:29 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:31 volumio volumio[973]: info: Disabling MyMusic plugin upnp Nov 23 17:03:31 volumio sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 23 17:03:31 volumio sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 17:03:33 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 17:03:33 volumio volumio[973]: info: Enabling MyMusic plugin upnp Nov 23 17:03:33 volumio volumio[973]: info: Enabling plugin upnp Nov 23 17:03:33 volumio volumio[973]: info: Loading plugin "upnp"... Nov 23 17:03:33 volumio volumio[973]: info: [1732356213844] Starting Upmpd Daemon Nov 23 17:03:33 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 17:03:33 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 17:03:33 volumio volumio[973]: Error: listen EADDRINUSE: address already in use :::6599 Nov 23 17:03:33 volumio volumio[973]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Nov 23 17:03:33 volumio volumio[973]: at listenInCluster (net.js:1379:12) Nov 23 17:03:33 volumio volumio[973]: at Server.listen (net.js:1465:7) Nov 23 17:03:33 volumio volumio[973]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 23 17:03:33 volumio volumio[973]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 23 17:03:33 volumio volumio[973]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 23 17:03:33 volumio volumio[973]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 23 17:03:33 volumio volumio[973]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Nov 23 17:03:33 volumio volumio[973]: code: 'EADDRINUSE', Nov 23 17:03:33 volumio volumio[973]: errno: -98, Nov 23 17:03:33 volumio volumio[973]: syscall: 'listen', Nov 23 17:03:33 volumio volumio[973]: address: '::', Nov 23 17:03:33 volumio volumio[973]: port: 6599 Nov 23 17:03:33 volumio volumio[973]: } Nov 23 17:03:33 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 17:03:34 volumio sudo[3785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 17:02 Nov 23 17:03:34 volumio sudo[3785]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"