-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Fri 2024-04-19 12:40:53 UTC. -- Apr 19 12:39:00 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:04 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:08 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:12 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:16 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:20 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:24 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:28 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:32 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:36 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:40 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:44 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:48 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:52 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:39:56 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 0 Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: Prefetching next song Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: DOING PREFETCH IN MPD Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand add "USB/276E-CCD7/DALI CD VOL 3/02 How It Feels.aif" Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand add "USB/276E-CCD7/DALI CD VOL 3/02 How It Feels.aif" took 2 milliseconds Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: ---------------------------- MPD announces system playlist update Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: Ignoring MPD Status Update Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand consume 1 took 4 milliseconds Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: ------------------------------ 7ms Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: error: Upnp client error: Error: This socket has been ended by the other party Apr 19 12:40:00 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:04 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:04 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::startPlaybackTimer Apr 19 12:40:04 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:08 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:12 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:16 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:20 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:24 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:28 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: [2024/04/19 12:40:29.966609, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: register_name_response: Answer name VOLUMIO 2 QDAC<00> differs from question name VOLUMIO 2 QDAC <00>. Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: [2024/04/19 12:40:29.967740, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: register_name_response: Answer name VOLUMIO 2 QDAC<03> differs from question name VOLUMIO 2 QDAC <03>. Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: [2024/04/19 12:40:29.968424, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 19 12:40:29 volumio-2-qdac-32.113 nmbd[562]: register_name_response: Answer name VOLUMIO 2 QDAC<20> differs from question name VOLUMIO 2 QDAC <20>. Apr 19 12:40:32 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:36 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand update Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand status Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: ---------------------------- MPD announces state update: update Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand update took 10 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ControllerMpd::getState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand status Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand status took 8 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand status took 5 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Command Router : Notfying DB Updatetrue Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: ---------------------------- MPD announces system playlist update Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Ignoring MPD Status Update Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: MPD Database updated - AlbumList cache refreshed Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: ---------------------------- MPD announces state update: database Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand status Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: ---------------------------- MPD announces state update: update Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ControllerMpd::getState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand status Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ControllerMpd::getState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::sendMpdCommand status Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseTrackInfo Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ------------------------------ 146ms Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: error: updateQueue error: null Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand status took 42 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ------------------------------ 54ms Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand status took 38 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: sendMpdCommand status took 32 milliseconds Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: Command Router : Notfying DB Updatefalse Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::Close All Modals sent Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: ControllerMpd::parseState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ControllerMpd::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::servicePushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: 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} Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: CURRENT POSITION 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::syncState stateService stop Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::syncState currentStatus stop Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: No code Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: ControllerMpd::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::servicePushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: 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} Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: verbose: CURRENT POSITION 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::syncState stateService stop Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::syncState currentStatus stop Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: No code Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreStateMachine::pushState Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CorePlayQueue::getTrack 1 Apr 19 12:40:37 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::volumioPushState Apr 19 12:40:38 volumio-2-qdac-32.113 volumio[612]: info: ------------------------------ 239ms Apr 19 12:40:38 volumio-2-qdac-32.113 volumio[612]: info: ------------------------------ 240ms Apr 19 12:40:40 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:44 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:48 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: Error: getaddrinfo -3007 Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: code: -3007, Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: errno: -3007, Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: syscall: 'getaddrinfo' Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: } Apr 19 12:40:51 volumio-2-qdac-32.113 volumio[612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:40:53 volumio-2-qdac-32.113 sudo[7181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 12:39 Apr 19 12:40:53 volumio-2-qdac-32.113 sudo[7181]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"