-- Logs begin at Thu 2019-02-14 18:12:02 CST, end at Thu 2025-07-17 19:37:10 CST. -- Jul 17 19:36:00 volumio-pi2w ntpd[678]: Soliciting pool server 103.186.118.221 Jul 17 19:36:01 volumio-pi2w volumio[778]: verbose: New Socket.io Connection to 192.168.31.238:3000 from 192.168.31.48 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 17 19:36:01 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 17 19:36:01 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioGetState Jul 17 19:36:01 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:03 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:05 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 17 19:36:06 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 17 19:36:07 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:08 volumio-pi2w ntpd[678]: Soliciting pool server 103.147.22.149 Jul 17 19:36:10 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 17 19:36:10 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 17 19:36:11 volumio-pi2w ntpd[678]: receive: Unexpected origin timestamp 0xec235ea9.11625a62 does not match aorg 0xec235eab.1162725c from server@103.186.118.216 xmt 0xec235eab.1e9d69cc Jul 17 19:36:11 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:12 volumio-pi2w volumio[778]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L moodeBlue.local. Jul 17 19:36:12 volumio-pi2w volumio[778]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L moodeRed.local. Jul 17 19:36:12 volumio-pi2w volumio[778]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L NAS00.local. Jul 17 19:36:12 volumio-pi2w volumio[778]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L pi4.local. Jul 17 19:36:15 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:19 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:23 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:25 volumio-pi2w ntpd[678]: Soliciting pool server 103.186.118.218 Jul 17 19:36:27 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:31 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:32 volumio-pi2w volumio[778]: info: Error browsing device NAS00 for shares: Command failed: smbclient --no-pass --debuglevel=0 -L NAS00.local. Jul 17 19:36:32 volumio-pi2w volumio[778]: Unable to initialize messaging context Jul 17 19:36:35 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:39 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:43 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:44 volumio-pi2w volumio[778]: verbose: New Socket.io Connection to 192.168.31.238 from 192.168.31.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 17 19:36:46 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 17 19:36:46 volumio-pi2w volumio[778]: info: Adding a new share Jul 17 19:36:46 volumio-pi2w volumio[778]: info: No correspondence found in configuration for share NAS on IP 192.168.31.2 Jul 17 19:36:46 volumio-pi2w sudo[4877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.2:/volume1/music /mnt/NAS/NAS Jul 17 19:36:46 volumio-pi2w sudo[4877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 19:36:47 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:51 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand rescan Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand rescan took 3 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Jul 17 19:36:53 volumio-pi2w volumio[778]: ---------------------------- MPD announces state update: update Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ControllerMpd::getState Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Jul 17 19:36:53 volumio-pi2w volumio[778]: ---------------------------- MPD announces state update: update Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Jul 17 19:36:53 volumio-pi2w volumio[778]: ---------------------------- MPD announces state update: update Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ControllerMpd::getState Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 13 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ControllerMpd::getState Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 14 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Command Router : Notfying DB Updatefalse Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ControllerMpd::pushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::servicePushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:53 volumio-pi2w volumio[778]: 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} Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: CURRENT POSITION 0 Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState stateService stop Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: No code Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ------------------------------ 129ms Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Jul 17 19:36:53 volumio-pi2w volumio[778]: ---------------------------- MPD announces state update: update Jul 17 19:36:53 volumio-pi2w volumio[778]: info: ControllerMpd::getState Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 131 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 131 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 122 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 120 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:53 volumio-pi2w volumio[778]: info: Command Router : Notfying DB Updatefalse Jul 17 19:36:53 volumio-pi2w volumio[778]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:36:53 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: Command Router : Notfying DB Updatefalse Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: Command Router : Notfying DB Updatefalse Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: ControllerMpd::parseState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ControllerMpd::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::servicePushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: 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} Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: CURRENT POSITION 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState stateService stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: No code Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ControllerMpd::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::servicePushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: 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} Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: CURRENT POSITION 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState stateService stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: No code Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ControllerMpd::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::servicePushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: 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} Jul 17 19:36:54 volumio-pi2w volumio[778]: verbose: CURRENT POSITION 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState stateService stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: No code Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreStateMachine::pushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CorePlayQueue::getTrack 0 Jul 17 19:36:54 volumio-pi2w volumio[778]: info: CoreCommandRouter::volumioPushState Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ------------------------------ 350ms Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ------------------------------ 351ms Jul 17 19:36:54 volumio-pi2w volumio[778]: info: ------------------------------ 226ms Jul 17 19:36:55 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:36:59 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:37:03 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:37:04 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 17 19:37:04 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 17 19:37:07 volumio-pi2w volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:37:09 volumio-pi2w ntpd[678]: Soliciting pool server 2406:2000:fc:437::1001 Jul 17 19:37:09 volumio-pi2w volumio[778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 19:37:09 volumio-pi2w volumio[778]: Error: dns service error: unknown Jul 17 19:37:09 volumio-pi2w volumio[778]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 17 19:37:09 volumio-pi2w volumio[778]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 17 19:37:09 volumio-pi2w volumio[778]: errorCode: -65537 Jul 17 19:37:09 volumio-pi2w volumio[778]: } Jul 17 19:37:09 volumio-pi2w volumio[778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 19:37:10 volumio-pi2w sudo[4956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 19:36 Jul 17 19:37:10 volumio-pi2w sudo[4956]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"