-- Logs begin at Thu 2019-02-14 12:11:59 SAST, end at Fri 2026-03-27 21:14:32 SAST. -- Mar 27 21:13:01 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:05 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:07 volumio-downstairs nmbd[748]: [2026/03/27 21:13:07.797459, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 27 21:13:07 volumio-downstairs nmbd[748]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.102 for name WORKGROUP<1d>. Mar 27 21:13:07 volumio-downstairs nmbd[748]: This response was from IP 192.168.0.150, reporting an IP address of 192.168.0.150. Mar 27 21:13:09 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:13 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:17 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:25 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:29 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:33 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:37 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:41 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:45 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:49 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:50 volumio-downstairs volumiologrotate[549]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 27 21:13:50 volumio-downstairs volumiologrotate[549]: ls: cannot access 'DOWNSTAIRS': No such file or directory Mar 27 21:13:53 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:13:57 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:01 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:06 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:10 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:14 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:19 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:20 volumio-downstairs volumio5-onboarding[1173]: time=2026-03-27T21:14:20.849+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.117:49851 Mar 27 21:14:21 volumio-downstairs volumio[814]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioGetState Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioGetQueue Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreStateMachine::getQueue Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CorePlayQueue::getQueue Mar 27 21:14:21 volumio-downstairs volumio[814]: info: Listing playlists Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 21:14:21 volumio-downstairs volumio[814]: info: Received Get System Info Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:14:21 volumio-downstairs volumio[814]: info: Discovery: Getting this device information Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioGetState Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioGetState Mar 27 21:14:21 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:22 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:22 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 21:14:26 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:26 volumio-downstairs volumio5-onboarding[1173]: time=2026-03-27T21:14:26.367+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.117:49851 Mar 27 21:14:26 volumio-downstairs volumio5-onboarding[1173]: time=2026-03-27T21:14:26.368+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.117:49851 Mar 27 21:14:26 volumio-downstairs volumio5-onboarding[1173]: time=2026-03-27T21:14:26.378+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.117:49853 Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Mar 27 21:14:27 volumio-downstairs volumio[814]: ---------------------------- MPD announces state update: update Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::getState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Mar 27 21:14:27 volumio-downstairs volumio[814]: ---------------------------- MPD announces state update: update Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::getState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Mar 27 21:14:27 volumio-downstairs volumio[814]: ---------------------------- MPD announces state update: update Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::getState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 15 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 11 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 11 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 8 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 7 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand status took 5 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Command Router : Notfying DB Updatetrue Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::Close All Modals sent Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Command Router : Notfying DB Updatetrue Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::Close All Modals sent Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Command Router : Notfying DB Updatetrue Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::Close All Modals sent Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseState Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand playlistinfo took 89 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::servicePushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":415451,"duration":479,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"820 Kbps","isStreaming":false,"title":"So in Love With You (Full Intention 12' mix)","artist":"Duke","album":"So in Love With You","uri":"NAS/music_streamer/physical_music/music_singles/Duke/So in Love With You/(06) So in Love With You (Full Intention 12'' mix) - Duke.flac","trackType":"flac"} Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: CURRENT POSITION 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState stateService play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Received an update from plugin. extracting info from payload Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::servicePushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":415451,"duration":479,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"820 Kbps","isStreaming":false,"title":"So in Love With You (Full Intention 12' mix)","artist":"Duke","album":"So in Love With You","uri":"NAS/music_streamer/physical_music/music_singles/Duke/So in Love With You/(06) So in Love With You (Full Intention 12'' mix) - Duke.flac","trackType":"flac"} Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: CURRENT POSITION 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState stateService play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Received an update from plugin. extracting info from payload Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ControllerMpd::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::servicePushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":415543,"duration":479,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1074 Kbps","isStreaming":false,"title":"So in Love With You (Full Intention 12' mix)","artist":"Duke","album":"So in Love With You","uri":"NAS/music_streamer/physical_music/music_singles/Duke/So in Love With You/(06) So in Love With You (Full Intention 12'' mix) - Duke.flac","trackType":"flac"} Mar 27 21:14:27 volumio-downstairs volumio[814]: verbose: CURRENT POSITION 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState stateService play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: Received an update from plugin. extracting info from payload Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreStateMachine::pushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CorePlayQueue::getTrack 5 Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::volumioPushState Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ------------------------------ 239ms Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ------------------------------ 237ms Mar 27 21:14:27 volumio-downstairs volumio[814]: info: ------------------------------ 234ms Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: info: [ASDebug] CurState: play PrevState: play Mar 27 21:14:27 volumio-downstairs volumio[814]: Searching plugin music_service/mpd Mar 27 21:14:27 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 27 21:14:29 volumio-downstairs volumio[814]: info: All search sources collected, pushing search results Mar 27 21:14:30 volumio-downstairs volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 27 21:14:30 volumio-downstairs volumio[814]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:14:30 volumio-downstairs volumio[814]: TypeError: Cannot read property 'slice' of null Mar 27 21:14:30 volumio-downstairs volumio[814]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 27 21:14:30 volumio-downstairs volumio[814]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 27 21:14:30 volumio-downstairs volumio[814]: at Object.onceWrapper (events.js:422:26) Mar 27 21:14:30 volumio-downstairs volumio[814]: at ClientRequest.emit (events.js:315:20) Mar 27 21:14:30 volumio-downstairs volumio[814]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 27 21:14:30 volumio-downstairs volumio[814]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 27 21:14:30 volumio-downstairs volumio[814]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 27 21:14:30 volumio-downstairs volumio[814]: at TLSSocket.emit (events.js:315:20) Mar 27 21:14:30 volumio-downstairs volumio[814]: at addChunk (internal/streams/readable.js:309:12) Mar 27 21:14:30 volumio-downstairs volumio[814]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 27 21:14:30 volumio-downstairs volumio[814]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 27 21:14:30 volumio-downstairs volumio[814]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 27 21:14:30 volumio-downstairs volumio[814]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:14:32 volumio-downstairs sudo[2413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 21:13 Mar 27 21:14:32 volumio-downstairs sudo[2413]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"