Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 07 22:27:04 volumio volumio[1167]: info: Pausing Remote Device: http://10.0.5.51 Dec 07 22:27:04 volumio volumio[1167]: info: Dec 07 22:27:04 volumio volumio[1167]: ---------------------------- Client requests Volumio pause Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::volumioPause Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::pause Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::stPlaybackTimer Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::servicePause Dec 07 22:27:04 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::servicePause Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 22:27:04 volumio volumio[1167]: info: ControllerMpd::pause Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand pause Dec 07 22:27:04 volumio volumio[1167]: info: ControllerMpd::getState Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand status Dec 07 22:27:04 volumio volumio[1167]: info: Dec 07 22:27:04 volumio volumio[1167]: ---------------------------- MPD announces state update: player Dec 07 22:27:04 volumio volumio[1167]: info: sendMpdCommand pause took 10 milliseconds Dec 07 22:27:04 volumio volumio[1167]: info: ControllerMpd::getState Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand status Dec 07 22:27:04 volumio volumio[1167]: info: sendMpdCommand status took 3 milliseconds Dec 07 22:27:04 volumio volumio[1167]: info: sendMpdCommand status took 3 milliseconds Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::parseState Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::parseState Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 22:27:04 volumio volumio[1167]: info: Done pausing: Dec 07 22:27:04 volumio volumio[1167]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 07 22:27:04 volumio volumio[1167]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::parseTrackInfo Dec 07 22:27:04 volumio volumio[1167]: verbose: ControllerMpd::parseTrackInfo Dec 07 22:27:04 volumio volumio[1167]: info: ControllerMpd::pushState Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::servicePushState Dec 07 22:27:04 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Dec 07 22:27:04 volumio volumio[1167]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":398368,"duration":463,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bitter Sweet Symphony - The Verve / Rockin'1000 That's Live Official","artist":"Rockin'1000","album":null,"uri":"https://rr1---sn-hhp-nujl.googlevideo.com/videoplayback?expire=1765189227&ei=C1I2aZzGOaaV2_gPmbfG8Ao&ip=24.220.196.190&id=o-AAf8qDaZg9BD1RDpurov72NiT6uA5iIe_N0UQbcvcSCP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=200&met=1765167627%2C&mh=qA&mm=31%2C29&mn=sn-hhp-nujl%2Csn-vgqsknse&ms=au%2Crdu&mv=m&mvi=1&pl=23&rms=au%2Cau&initcwndbps=4300000&bui=AYUSA3Dtz3pWQ6p5RsCkiDyYVnJOuc5BsxintkuMEHAWwxA2x8rGIY_ibxKXmqfHgS6SAG8AIApD2iEU&spc=wH4Qq3z7C6Fm7hIDdbO3L89kITZL2aKlpetkW3XVkFns8KRtPC1WZ1pV_WVXsaNWmMM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FHLGaB4HM0QXrXWjcmKsERUQ&rqh=1&gir=yes&clen=7173965&dur=462.501&lmt=1753072700634451&mt=1765167406&fvip=2&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=4532534&n=avfaJCTOItgUgw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPdoRBXuVHYmaBws3s-vuVXKfWdaAQmzN_IMaZksmblfAiEAxarV0JGhkE8wu-QroT2sGtfRmlU2WoxvATggUXLNY8A%3D&sig=AJfQdSswRAIgJ7RivRoqikg7JFPyztC7ynY35ZQVqnQpMSLSd3UpFjECIEEg7hS1dFNxKuOA7HJ0UPnQn1s-atUzgcoxST8g_S4h&pot=MlVapR2EmveB8Fqbu5fE0XKdE9KylTjQDTlv5Rj5U-x1bRZHTgUvA8gsnmKF53xFcIKUdMqsj4c9bNRKE9cHg0rzMmBqUShA549fX7kM-E7QNfSkTpjS&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 07 22:27:04 volumio volumio[1167]: verbose: CURRENT POSITION 0 Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::syncState stateService pause Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::syncState currentStatus pause Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::pushState Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::volumioPushState Dec 07 22:27:04 volumio volumio[1167]: info: MRS: Pushing multiroomSync output update for this device Dec 07 22:27:04 volumio volumio[1167]: info: MRS: Pushing multiroomSync output Dec 07 22:27:04 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:04 volumio volumio[1167]: info: CoreStateMachine::stPlaybackTimer Dec 07 22:27:04 volumio volumio[1167]: info: ------------------------------ 25ms Dec 07 22:27:04 volumio volumio[1167]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Dec 07 22:27:05 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:05 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:05 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:06 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 07 22:27:06 volumio volumio[1167]: error: MRS: impossible to set browserPlayback volume: device not found Dec 07 22:27:10 volumio volumio[1167]: verbose: New Socket.io Connection to 10.0.5.51 from 10.0.1.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 07 22:27:10 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:10 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 22:27:10 volumio volumio[1167]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 07 22:27:10 volumio volumio[1167]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 22:27:10 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 22:27:10 volumio go-librespot[1635]: time="2025-12-07T22:27:10-06:00" level=trace msg="sent dealer ping" Dec 07 22:27:10 volumio volumio[1167]: info: CoreCommandRouter::volumioGetQueue Dec 07 22:27:10 volumio volumio[1167]: info: CoreStateMachine::getQueue Dec 07 22:27:10 volumio volumio[1167]: info: CorePlayQueue::getQueue Dec 07 22:27:10 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:10 volumio go-librespot[1635]: time="2025-12-07T22:27:10-06:00" level=trace msg="received dealer pong" Dec 07 22:27:14 volumio go-librespot[1635]: time="2025-12-07T22:27:14-06:00" level=trace msg="received accesspoint ping" Dec 07 22:27:14 volumio go-librespot[1635]: time="2025-12-07T22:27:14-06:00" level=trace msg="received accesspoint pong ack" Dec 07 22:27:15 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:25 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:25 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:25 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:32 volumio ntpd[1001]: PROTO: 23.186.168.130 unlink local addr 10.0.5.51 -> Dec 07 22:27:33 volumio ntpd[1001]: PROTO: 23.186.168.125 unlink local addr 10.0.5.51 -> Dec 07 22:27:35 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:39 volumio ntpd[1001]: PROTO: 102.129.185.135 unlink local addr 10.0.5.51 -> Dec 07 22:27:40 volumio go-librespot[1635]: time="2025-12-07T22:27:40-06:00" level=trace msg="sent dealer ping" Dec 07 22:27:40 volumio go-librespot[1635]: time="2025-12-07T22:27:40-06:00" level=trace msg="received dealer pong" Dec 07 22:27:43 volumio ntpd[1001]: PROTO: 216.144.228.179 unlink local addr 10.0.5.51 -> Dec 07 22:27:45 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:27:45 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:45 volumio volumio[1167]: info: Listing playlists Dec 07 22:27:55 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Dec 07 22:28:05 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 07 22:28:05 volumio volumio[1167]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 07 22:28:05 volumio volumio[1167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 22:28:05 volumio volumio[1167]: TypeError: Cannot read properties of undefined (reading 'then') Dec 07 22:28:05 volumio volumio[1167]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 07 22:28:05 volumio volumio[1167]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 07 22:28:05 volumio volumio[1167]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 07 22:28:05 volumio volumio[1167]: at Socket.emit (node:events:514:28) Dec 07 22:28:05 volumio volumio[1167]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 07 22:28:05 volumio volumio[1167]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 07 22:28:05 volumio volumio[1167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 22:28:06 volumio sudo[3939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 22:27' Dec 07 22:28:06 volumio sudo[3939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"