-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Sat 2026-01-10 15:31:23 GMT. -- Jan 10 15:30:09 volumio go-librespot[6689]: time="2026-01-10T15:30:09Z" level=trace msg="sent dealer ping" Jan 10 15:30:10 volumio go-librespot[6689]: time="2026-01-10T15:30:10Z" level=trace msg="received dealer pong" Jan 10 15:30:16 volumio go-librespot[6689]: time="2026-01-10T15:30:16Z" level=trace msg="received accesspoint ping" Jan 10 15:30:16 volumio go-librespot[6689]: time="2026-01-10T15:30:16Z" level=trace msg="received accesspoint pong ack" Jan 10 15:30:22 volumio volumio[1285]: error: error Jan 10 15:30:39 volumio go-librespot[6689]: time="2026-01-10T15:30:39Z" level=trace msg="sent dealer ping" Jan 10 15:30:39 volumio go-librespot[6689]: time="2026-01-10T15:30:39Z" level=trace msg="received dealer pong" Jan 10 15:30:48 volumio volumio[1285]: info: Jan 10 15:30:48 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Jan 10 15:30:48 volumio volumio[1285]: info: Ignoring MPD Status Update Jan 10 15:30:48 volumio volumio[1285]: info: Jan 10 15:30:48 volumio volumio[1285]: ---------------------------- MPD announces state update: player Jan 10 15:30:48 volumio volumio[1285]: info: ControllerMpd::getState Jan 10 15:30:48 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:30:48 volumio volumio[1285]: info: ------------------------------ 2ms Jan 10 15:30:48 volumio volumio[1285]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:30:48 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 10 15:30:48 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:30:48 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 10 15:30:48 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:30:48 volumio volumio[1285]: info: ControllerMpd::pushState Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 10 15:30:48 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:30:48 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2482548,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"LIETUS","artist":"Lietus","album":null,"uri":"http://radio.m-1.fm:80/LIETUS","trackType":""} Jan 10 15:30:48 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 10 15:30:48 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Jan 10 15:30:48 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:30:48 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Jan 10 15:30:48 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:30:48 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:30:48 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:30:48 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:30:48 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:30:48 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:30:48 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:30:48 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:30:48 volumio volumio[1285]: info: ------------------------------ 10ms Jan 10 15:30:48 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status play Jan 10 15:30:48 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status play Jan 10 15:30:48 volumio sudo[20258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jan 10 15:30:48 volumio sudo[20258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:30:48 volumio sudo[20258]: pam_unix(sudo:session): session closed for user root Jan 10 15:30:48 volumio sudo[20261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jan 10 15:30:48 volumio sudo[20261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:30:48 volumio volumio[1285]: info: peppymeterbasic Daemon Started Jan 10 15:30:48 volumio sudo[20261]: pam_unix(sudo:session): session closed for user root Jan 10 15:30:48 volumio volumio[1285]: info: peppymeterbasic Daemon Started Jan 10 15:30:54 volumio volumio[1285]: info: Jan 10 15:30:54 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Jan 10 15:30:54 volumio volumio[1285]: info: Ignoring MPD Status Update Jan 10 15:30:54 volumio volumio[1285]: info: Jan 10 15:30:54 volumio volumio[1285]: ---------------------------- MPD announces state update: player Jan 10 15:30:54 volumio volumio[1285]: info: ControllerMpd::getState Jan 10 15:30:54 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:30:54 volumio volumio[1285]: info: ------------------------------ 1ms Jan 10 15:30:54 volumio volumio[1285]: info: sendMpdCommand status took 1 milliseconds Jan 10 15:30:54 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 10 15:30:54 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:30:54 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:30:54 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:30:54 volumio volumio[1285]: info: ControllerMpd::pushState Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 10 15:30:54 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:30:54 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2488517,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"JESSICA SHY - APKABINK","artist":"Lietus","album":null,"uri":"http://radio.m-1.fm:80/LIETUS","trackType":""} Jan 10 15:30:54 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 10 15:30:54 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Jan 10 15:30:54 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:30:54 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Jan 10 15:30:54 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:30:54 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:30:54 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:30:54 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:30:54 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:30:54 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:30:54 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:30:54 volumio volumio[1285]: info: ------------------------------ 12ms Jan 10 15:30:54 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status play Jan 10 15:30:54 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status play Jan 10 15:30:54 volumio sudo[20323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jan 10 15:30:54 volumio sudo[20323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:30:54 volumio sudo[20323]: pam_unix(sudo:session): session closed for user root Jan 10 15:30:54 volumio sudo[20326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jan 10 15:30:54 volumio sudo[20326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:30:54 volumio volumio[1285]: info: peppymeterbasic Daemon Started Jan 10 15:30:54 volumio sudo[20326]: pam_unix(sudo:session): session closed for user root Jan 10 15:30:54 volumio volumio[1285]: info: peppymeterbasic Daemon Started Jan 10 15:31:09 volumio go-librespot[6689]: time="2026-01-10T15:31:09Z" level=trace msg="sent dealer ping" Jan 10 15:31:09 volumio go-librespot[6689]: time="2026-01-10T15:31:09Z" level=trace msg="received dealer pong" Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::volumioStop Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::stop Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::stPlaybackTimer Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::updateTrackBlock Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrackBlock Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:31:20 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:31:20 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::serviceStop Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::serviceStop Jan 10 15:31:20 volumio volumio[1285]: info: [1768059080816] ControllerWebradio::stop Jan 10 15:31:20 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:31:20 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status stop Jan 10 15:31:20 volumio sudo[20362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 10 15:31:20 volumio sudo[20362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:31:20 volumio volumio[1285]: info: Jan 10 15:31:20 volumio volumio[1285]: ---------------------------- MPD announces state update: player Jan 10 15:31:20 volumio volumio[1285]: info: sendMpdCommand stop took 37 milliseconds Jan 10 15:31:20 volumio volumio[1285]: info: ControllerMpd::getState Jan 10 15:31:20 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:31:20 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jan 10 15:31:20 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jan 10 15:31:20 volumio volumio[1285]: info: sendMpdCommand status took 1 milliseconds Jan 10 15:31:20 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 10 15:31:20 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:31:20 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:31:20 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:31:20 volumio volumio[1285]: info: ControllerMpd::pushState Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 10 15:31:20 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 10 15:31:20 volumio volumio[1285]: info: MRS: Pushing multiroomSync output update for this device Jan 10 15:31:20 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Jan 10 15:31:20 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 10 15:31:20 volumio volumio[1285]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 10 15:31:20 volumio volumio[1285]: info: ------------------------------ 12ms Jan 10 15:31:20 volumio volumio[1285]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 15:31:20 volumio volumio[1285]: info: PeppyMeterBasic ---peppymeterbasic status stop Jan 10 15:31:20 volumio sudo[20367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 10 15:31:20 volumio sudo[20367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:31:20 volumio volumio[1285]: info: touch_display: Setting screensaver timeout to 360 seconds. Jan 10 15:31:20 volumio volumio[1285]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 15:31:20 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jan 10 15:31:20 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jan 10 15:31:20 volumio sudo[20367]: pam_unix(sudo:session): session closed for user root Jan 10 15:31:20 volumio volumio[1285]: info: peppymeterbasic Daemon Stop Jan 10 15:31:20 volumio sudo[20362]: pam_unix(sudo:session): session closed for user root Jan 10 15:31:20 volumio volumio[1285]: info: peppymeterbasic Daemon Stop Jan 10 15:31:22 volumio volumio[1285]: error: error Jan 10 15:31:22 volumio volumio[1285]: info: Executing endpoint metavolumio Jan 10 15:31:22 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 15:31:23 volumio volumio[1285]: info: MRS: STARTING volumioStreaming Jan 10 15:31:23 volumio sudo[20384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream2492.ts /tmp/hls/stream2493.ts /tmp/hls/stream2494.ts /tmp/hls/stream2495.ts /tmp/hls/stream2496.ts Jan 10 15:31:23 volumio sudo[20384]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:31:23 volumio sudo[20384]: pam_unix(sudo:session): session closed for user root Jan 10 15:31:23 volumio volumio[1285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:31:23 volumio volumio[1285]: TypeError: Cannot read property 'send' of null Jan 10 15:31:23 volumio volumio[1285]: at Client.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:128:11) Jan 10 15:31:23 volumio volumio[1285]: at Channel.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:34:12) Jan 10 15:31:23 volumio volumio[1285]: at fn.Controller.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:28:16) Jan 10 15:31:23 volumio volumio[1285]: at fn.RequestResponseController.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:34:8) Jan 10 15:31:23 volumio volumio[1285]: at fn.MediaController.sessionRequest (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:91:8) Jan 10 15:31:23 volumio volumio[1285]: at fn.MediaController.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:107:8) Jan 10 15:31:23 volumio volumio[1285]: at DefaultMediaReceiver.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/default-media-receiver.js:42:19) Jan 10 15:31:23 volumio volumio[1285]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:5038) Jan 10 15:31:23 volumio volumio[1285]: at listOnTimeout (internal/timers.js:554:17) Jan 10 15:31:23 volumio volumio[1285]: at processTimers (internal/timers.js:497:7) Jan 10 15:31:23 volumio sudo[20387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Jan 10 15:31:23 volumio sudo[20387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:31:23 volumio volumio[1285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:31:23 volumio systemd[1]: Stopping VolumioStreamingService... Jan 10 15:31:23 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Jan 10 15:31:23 volumio systemd[1]: volumioStreaming.service: Succeeded. Jan 10 15:31:23 volumio systemd[1]: Stopped VolumioStreamingService. Jan 10 15:31:23 volumio systemd[1]: Started VolumioStreamingService. Jan 10 15:31:23 volumio sudo[20387]: pam_unix(sudo:session): session closed for user root Jan 10 15:31:23 volumio sudo[20398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 10 15:31:23 volumio sudo[20398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:31:23 volumio sudo[20398]: pam_unix(sudo:session): session closed for user root Jan 10 15:31:23 volumio sudo[20404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 15:30 Jan 10 15:31:23 volumio sudo[20404]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"