-- Logs begin at Wed 2025-06-25 06:57:05 BST, end at Mon 2025-06-30 09:57:20 BST. -- Jun 30 09:56:06 pk-volumio volumiologrotate[569]: ls: cannot access '/var/log/samba/log.wb-PK': No such file or directory Jun 30 09:56:06 pk-volumio volumiologrotate[569]: ls: cannot access 'VOLUMIO': No such file or directory Jun 30 09:56:08 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 09:56:08 pk-volumio volumio[18537]: info: Received Get System Info Jun 30 09:56:08 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 09:56:08 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 09:56:08 pk-volumio volumio[18537]: info: Discovery: Getting this device information Jun 30 09:56:08 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:56:08 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 09:56:23 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 09:56:23 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 09:56:23 pk-volumio volumio[18537]: info: Discovery: Getting this device information Jun 30 09:56:23 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:56:23 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 09:56:53 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 09:56:53 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 09:56:53 pk-volumio volumio[18537]: info: Discovery: Getting this device information Jun 30 09:56:53 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:56:53 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 09:57:06 pk-volumio volumiologrotate[569]: ls: cannot access '/var/log/samba/log.wb-PK': No such file or directory Jun 30 09:57:06 pk-volumio volumiologrotate[569]: ls: cannot access 'VOLUMIO': No such file or directory Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPlay Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::play index undefined Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 09:57:06 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::startPlaybackTimer Jun 30 09:57:06 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:06 pk-volumio volumio[18537]: info: [1751273826691] ControllerTidal::resume Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 09:57:06 pk-volumio volumio[18537]: info: ControllerMpd::resume Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand play Jun 30 09:57:06 pk-volumio volumio[18537]: info: Jun 30 09:57:06 pk-volumio volumio[18537]: ---------------------------- MPD announces state update: player Jun 30 09:57:06 pk-volumio volumio[18537]: info: sendMpdCommand play took 3 milliseconds Jun 30 09:57:06 pk-volumio volumio[18537]: info: ControllerMpd::getState Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand status Jun 30 09:57:06 pk-volumio volumio[18537]: info: sendMpdCommand status took 0 milliseconds Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: ControllerMpd::parseState Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 09:57:06 pk-volumio volumio[18537]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: ControllerMpd::parseTrackInfo Jun 30 09:57:06 pk-volumio volumio[18537]: info: ControllerMpd::pushState Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreCommandRouter::servicePushState Jun 30 09:57:06 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20706,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"780 Kbps","isStreaming":false,"title":"352285254&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352285254&soundQuality=LOSSLESS","trackType":"tidal"} Jun 30 09:57:06 pk-volumio volumio[18537]: verbose: CURRENT POSITION 0 Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::syncState stateService play Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::syncState currentStatus pause Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreStateMachine::pushState Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPushState Jun 30 09:57:06 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output update for this device Jun 30 09:57:06 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output Jun 30 09:57:06 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:57:06 pk-volumio volumio[18537]: info: ------------------------------ 15ms Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::pause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::stPlaybackTimer Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::servicePause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::servicePause Jun 30 09:57:07 pk-volumio volumio[18537]: info: [1751273827827] ControllerTidal::pause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 09:57:07 pk-volumio volumio[18537]: info: ControllerMpd::pause Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand pause Jun 30 09:57:07 pk-volumio volumio[18537]: info: Jun 30 09:57:07 pk-volumio volumio[18537]: ---------------------------- MPD announces state update: player Jun 30 09:57:07 pk-volumio volumio[18537]: info: sendMpdCommand pause took 3 milliseconds Jun 30 09:57:07 pk-volumio volumio[18537]: info: ControllerMpd::getState Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand status Jun 30 09:57:07 pk-volumio volumio[18537]: info: sendMpdCommand status took 1 milliseconds Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: ControllerMpd::parseState Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 09:57:07 pk-volumio volumio[18537]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: ControllerMpd::parseTrackInfo Jun 30 09:57:07 pk-volumio volumio[18537]: info: ControllerMpd::pushState Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::servicePushState Jun 30 09:57:07 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22079,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"352285254&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352285254&soundQuality=LOSSLESS","trackType":"tidal"} Jun 30 09:57:07 pk-volumio volumio[18537]: verbose: CURRENT POSITION 0 Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::syncState stateService pause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::syncState currentStatus pause Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::pushState Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPushState Jun 30 09:57:07 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output update for this device Jun 30 09:57:07 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:57:07 pk-volumio volumio[18537]: info: CoreStateMachine::stPlaybackTimer Jun 30 09:57:07 pk-volumio volumio[18537]: info: ------------------------------ 15ms Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPlay Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::play index undefined Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 09:57:09 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::startPlaybackTimer Jun 30 09:57:09 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:09 pk-volumio volumio[18537]: info: [1751273829051] ControllerTidal::resume Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 09:57:09 pk-volumio volumio[18537]: info: ControllerMpd::resume Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand play Jun 30 09:57:09 pk-volumio volumio[18537]: info: Jun 30 09:57:09 pk-volumio volumio[18537]: ---------------------------- MPD announces state update: player Jun 30 09:57:09 pk-volumio volumio[18537]: info: sendMpdCommand play took 3 milliseconds Jun 30 09:57:09 pk-volumio volumio[18537]: info: ControllerMpd::getState Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand status Jun 30 09:57:09 pk-volumio volumio[18537]: info: sendMpdCommand status took 1 milliseconds Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: ControllerMpd::parseState Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 09:57:09 pk-volumio volumio[18537]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: ControllerMpd::parseTrackInfo Jun 30 09:57:09 pk-volumio volumio[18537]: info: ControllerMpd::pushState Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreCommandRouter::servicePushState Jun 30 09:57:09 pk-volumio volumio[18537]: info: CorePlayQueue::getTrack 0 Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23314,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"814 Kbps","isStreaming":false,"title":"352285254&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352285254&soundQuality=LOSSLESS","trackType":"tidal"} Jun 30 09:57:09 pk-volumio volumio[18537]: verbose: CURRENT POSITION 0 Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::syncState stateService play Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::syncState currentStatus pause Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreStateMachine::pushState Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioPushState Jun 30 09:57:09 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output update for this device Jun 30 09:57:09 pk-volumio volumio[18537]: info: MRS: Pushing multiroomSync output Jun 30 09:57:09 pk-volumio volumio[18537]: info: CoreCommandRouter::volumioGetState Jun 30 09:57:09 pk-volumio volumio[18537]: info: ------------------------------ 13ms Jun 30 09:57:20 pk-volumio volumio[18537]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jun 30 09:57:20 pk-volumio volumio[18537]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jun 30 09:57:20 pk-volumio volumio[18537]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 09:57:20 pk-volumio volumio[18537]: TypeError: Cannot read property 'then' of undefined Jun 30 09:57:20 pk-volumio volumio[18537]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jun 30 09:57:20 pk-volumio volumio[18537]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Jun 30 09:57:20 pk-volumio volumio[18537]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Jun 30 09:57:20 pk-volumio volumio[18537]: at Socket.emit (events.js:315:20) Jun 30 09:57:20 pk-volumio volumio[18537]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 30 09:57:20 pk-volumio volumio[18537]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 30 09:57:20 pk-volumio volumio[18537]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 09:57:20 pk-volumio sudo[13101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 09:56 Jun 30 09:57:20 pk-volumio sudo[13101]: 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="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"