-- Logs begin at Thu 2019-02-14 11:12:08 CET, end at Sat 2025-02-15 08:34:41 CET. -- Feb 15 08:33:47 volumio nmbd[553]: [2025/02/15 08:33:47.403902, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 15 08:33:47 volumio nmbd[553]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.38 for name WORKGROUP<1d>. Feb 15 08:33:47 volumio nmbd[553]: This response was from IP 192.168.0.44, reporting an IP address of 192.168.0.44. Feb 15 08:34:28 volumio volumio[689]: info: Clearing queue after UPNP request Feb 15 08:34:28 volumio volumio[689]: info: CoreStateMachine::ClearQueue Feb 15 08:34:28 volumio volumio[689]: info: CoreStateMachine::stop Feb 15 08:34:28 volumio volumio[689]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 08:34:28 volumio volumio[689]: info: CorePlayQueue::clearPlayQueue Feb 15 08:34:28 volumio volumio[689]: info: CorePlayQueue::saveQueue Feb 15 08:34:28 volumio volumio[689]: info: CoreCommandRouter::volumioPushState Feb 15 08:34:28 volumio volumio[689]: info: CoreCommandRouter::volumioPushQueue Feb 15 08:34:28 volumio volumio[689]: error: Upnp client error: Error: This socket has been ended by the other party Feb 15 08:34:30 volumio volumio[689]: info: Starting UPNP Playback Feb 15 08:34:30 volumio volumio[689]: info: Preparing playback through UPNP Feb 15 08:34:30 volumio volumio[689]: info: CoreCommandRouter::volumioGetState Feb 15 08:34:30 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:30 volumio volumio[689]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 30ms Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 23ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 27ms Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 16ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 23ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 27ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 27ms Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 14ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 25ms Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 14ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:31 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 26ms Feb 15 08:34:31 volumio volumio[689]: info: ------------------------------ 14ms Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:31 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:31 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:31 volumio volumio[689]: info: Feb 15 08:34:31 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:31 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:31 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand status took 291 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand status took 287 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:32 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand status took 96 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand playlistinfo took 95 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand playlistinfo took 75 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseTrackInfo Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:32 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:32 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:32 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus stop Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:32 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:32 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:32 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus play Feb 15 08:34:32 volumio volumio[689]: info: Received an update from plugin. extracting info from payload Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::pushState Feb 15 08:34:32 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 565ms Feb 15 08:34:32 volumio volumio[689]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 571ms Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand status took 323 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 346ms Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand status took 307 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand playlistinfo took 270 milliseconds Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseTrackInfo Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:32 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:32 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:32 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus play Feb 15 08:34:32 volumio volumio[689]: info: Received an update from plugin. extracting info from payload Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::pushState Feb 15 08:34:32 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:32 volumio volumio[689]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 556ms Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces system playlist update Feb 15 08:34:32 volumio volumio[689]: info: Ignoring MPD Status Update Feb 15 08:34:32 volumio volumio[689]: info: Feb 15 08:34:32 volumio volumio[689]: ---------------------------- MPD announces state update: player Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::getState Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand status Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand playlistinfo took 179 milliseconds Feb 15 08:34:32 volumio volumio[689]: info: sendMpdCommand playlistinfo took 184 milliseconds Feb 15 08:34:32 volumio volumio[689]: verbose: ControllerMpd::parseTrackInfo Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:32 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:32 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:32 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus play Feb 15 08:34:32 volumio volumio[689]: info: Received an update from plugin. extracting info from payload Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::pushState Feb 15 08:34:32 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:32 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:32 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:32 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:32 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus play Feb 15 08:34:32 volumio volumio[689]: info: Received an update from plugin. extracting info from payload Feb 15 08:34:32 volumio volumio[689]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 08:34:32 volumio volumio[689]: info: CoreStateMachine::pushState Feb 15 08:34:32 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:32 volumio volumio[689]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 731ms Feb 15 08:34:32 volumio volumio[689]: info: ------------------------------ 688ms Feb 15 08:34:33 volumio volumio[689]: info: ------------------------------ 441ms Feb 15 08:34:33 volumio volumio[689]: info: sendMpdCommand status took 535 milliseconds Feb 15 08:34:33 volumio volumio[689]: verbose: ControllerMpd::parseState Feb 15 08:34:33 volumio volumio[689]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 08:34:33 volumio volumio[689]: info: sendMpdCommand playlistinfo took 412 milliseconds Feb 15 08:34:33 volumio volumio[689]: verbose: ControllerMpd::parseTrackInfo Feb 15 08:34:33 volumio volumio[689]: info: ControllerMpd::pushState Feb 15 08:34:33 volumio volumio[689]: info: CoreCommandRouter::servicePushState Feb 15 08:34:33 volumio volumio[689]: verbose: In UPNP mode Feb 15 08:34:33 volumio volumio[689]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://51.75.95.60:9562/stream","trackType":""} Feb 15 08:34:33 volumio volumio[689]: verbose: CURRENT POSITION 2 Feb 15 08:34:33 volumio volumio[689]: info: CoreStateMachine::syncState stateService play Feb 15 08:34:34 volumio volumio[689]: info: CoreStateMachine::syncState currentStatus play Feb 15 08:34:34 volumio volumio[689]: info: Received an update from plugin. extracting info from payload Feb 15 08:34:34 volumio volumio[689]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 08:34:34 volumio volumio[689]: info: CoreStateMachine::pushState Feb 15 08:34:34 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:34 volumio volumio[689]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 15 08:34:34 volumio volumio[689]: info: ------------------------------ 1755ms Feb 15 08:34:35 volumio volumio[689]: info: Starting UPNP Playback Feb 15 08:34:35 volumio volumio[689]: info: Preparing playback through UPNP Feb 15 08:34:35 volumio volumio[689]: info: Consume mode Feb 15 08:34:35 volumio volumio[689]: info: CoreCommandRouter::volumioGetState Feb 15 08:34:35 volumio volumio[689]: info: CorePlayQueue::getTrack 2 Feb 15 08:34:35 volumio volumio[689]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 08:34:35 volumio volumio[689]: TypeError: Cannot read property 'name' of undefined Feb 15 08:34:35 volumio volumio[689]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 15 08:34:35 volumio volumio[689]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 15 08:34:35 volumio volumio[689]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Feb 15 08:34:35 volumio volumio[689]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Feb 15 08:34:35 volumio volumio[689]: at Socket.emit (events.js:315:20) Feb 15 08:34:35 volumio volumio[689]: at addChunk (internal/streams/readable.js:309:12) Feb 15 08:34:35 volumio volumio[689]: at readableAddChunk (internal/streams/readable.js:280:11) Feb 15 08:34:35 volumio volumio[689]: at Socket.Readable.push (internal/streams/readable.js:223:10) Feb 15 08:34:35 volumio volumio[689]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Feb 15 08:34:35 volumio volumio[689]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 08:34:41 volumio sudo[6350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 08:33 Feb 15 08:34:41 volumio sudo[6350]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"