-- Logs begin at Thu 2019-02-14 05:11:59 -05, end at Sun 2025-05-11 11:00:29 -05. -- May 11 10:59:03 zero ntpd[694]: Soliciting pool server 2803:480:1::3 May 11 10:59:07 zero volumio[839]: verbose: ----------------------------------- May 11 10:59:07 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 206) (YouTube) Incoming message: 'noop' May 11 10:59:07 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 206) (YouTube) Not handled: 'noop' May 11 10:59:13 zero volumio[839]: verbose: ----------------------------------- May 11 10:59:13 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 206) (YouTube Music) Incoming message: 'noop' May 11 10:59:13 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 206) (YouTube Music) Not handled: 'noop' May 11 10:59:16 zero go-librespot[1121]: time="2025-05-11T10:59:16-05:00" level=trace msg="sent dealer ping" May 11 10:59:16 zero go-librespot[1121]: time="2025-05-11T10:59:16-05:00" level=trace msg="received dealer pong" May 11 10:59:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 10:59:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 10:59:20 zero volumio[839]: info: Discovery: Getting this device information May 11 10:59:20 zero volumio[839]: info: CoreCommandRouter::volumioGetState May 11 10:59:20 zero volumio[839]: info: CorePlayQueue::getTrack 208 May 11 10:59:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 10:59:33 zero volumio[839]: verbose: ----------------------------------- May 11 10:59:33 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 207) (YouTube) Incoming message: 'noop' May 11 10:59:33 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 207) (YouTube) Not handled: 'noop' May 11 10:59:37 zero volumio[839]: verbose: ----------------------------------- May 11 10:59:37 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 207) (YouTube Music) Incoming message: 'noop' May 11 10:59:37 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 207) (YouTube Music) Not handled: 'noop' May 11 10:59:37 zero volumio[839]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... May 11 10:59:37 zero volumio[839]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=207&CI=0&RID=rpc&SID=E148A8B11D247AA9&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=Gdb7UIEpvKnOYJPedvxKI96YOr66_-z5Yd2-X-zaolk&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p8zIVxBInXSaIvDZksWKiy7e3srBGTuHsb9dCEpQvudXIkMQRL8cyHLhuTmjivHCRBccFK57OFi6cN7nOXo2gbjpyfBf78ii8ds3wM_UBj3cYvLLOY&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=e88fd597e0e2 May 11 10:59:38 zero volumio[839]: verbose: [yt-cast-receiver] RPC connection established. May 11 10:59:38 zero volumio[839]: verbose: ----------------------------------- May 11 10:59:38 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 208) (YouTube Music) Incoming message: 'noop' May 11 10:59:38 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 208) (YouTube Music) Not handled: 'noop' May 11 10:59:46 zero go-librespot[1121]: time="2025-05-11T10:59:46-05:00" level=trace msg="sent dealer ping" May 11 10:59:46 zero go-librespot[1121]: time="2025-05-11T10:59:46-05:00" level=trace msg="received dealer pong" May 11 10:59:47 zero go-librespot[1121]: time="2025-05-11T10:59:47-05:00" level=trace msg="received accesspoint ping" May 11 10:59:47 zero go-librespot[1121]: time="2025-05-11T10:59:47-05:00" level=trace msg="received accesspoint pong ack" May 11 10:59:50 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 10:59:50 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 10:59:50 zero volumio[839]: info: Discovery: Getting this device information May 11 10:59:50 zero volumio[839]: info: CoreCommandRouter::volumioGetState May 11 10:59:50 zero volumio[839]: info: CorePlayQueue::getTrack 208 May 11 10:59:50 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 11:00:01 zero volumio[839]: verbose: ----------------------------------- May 11 11:00:01 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 208) (YouTube) Incoming message: 'noop' May 11 11:00:01 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 208) (YouTube) Not handled: 'noop' May 11 11:00:05 zero volumio[839]: verbose: ----------------------------------- May 11 11:00:05 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 209) (YouTube Music) Incoming message: 'noop' May 11 11:00:05 zero volumio[839]: verbose: [yt-cast-receiver] (AID: 209) (YouTube Music) Not handled: 'noop' May 11 11:00:07 zero ntpd[694]: Soliciting pool server 2803:480:1::3 May 11 11:00:15 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 11:00:15 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 11:00:15 zero volumio[839]: info: Discovery: Getting this device information May 11 11:00:15 zero volumio[839]: info: CoreCommandRouter::volumioGetState May 11 11:00:15 zero volumio[839]: info: CorePlayQueue::getTrack 208 May 11 11:00:15 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 11:00:16 zero go-librespot[1121]: time="2025-05-11T11:00:16-05:00" level=trace msg="sent dealer ping" May 11 11:00:16 zero go-librespot[1121]: time="2025-05-11T11:00:16-05:00" level=trace msg="received dealer pong" May 11 11:00:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 11:00:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 11:00:20 zero volumio[839]: info: Discovery: Getting this device information May 11 11:00:20 zero volumio[839]: info: CoreCommandRouter::volumioGetState May 11 11:00:20 zero volumio[839]: info: CorePlayQueue::getTrack 208 May 11 11:00:20 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 11:00:23 zero volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput May 11 11:00:23 zero volumio[839]: info: MRS: Starting cast device: Google-Cast-Group-3AD1498CE83441A584480E0DA82089CD May 11 11:00:23 zero volumio[839]: info: Enabled audio output: Google-Cast-Group-3AD1498CE83441A584480E0DA82089CD May 11 11:00:23 zero volumio[839]: info: MRS: Setting this device as Streaming Server May 11 11:00:23 zero volumio[839]: info: May 11 11:00:23 zero volumio[839]: [1746979223759] ---------------------------- MRS: Setting Streaming Server May 11 11:00:23 zero volumio[839]: info: MRS: enable multiroom server output May 11 11:00:23 zero volumio[839]: info: MRS: Set multiroom target PCM to volumioMultiRoom May 11 11:00:23 zero volumio[839]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom May 11 11:00:23 zero volumio[839]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 11 11:00:23 zero volumio[839]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback May 11 11:00:23 zero volumio[839]: info: MRS: STARTING volumioStreaming May 11 11:00:23 zero sudo[12970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 11 11:00:23 zero sudo[12970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 11:00:23 zero sudo[12970]: pam_unix(sudo:session): session closed for user root May 11 11:00:23 zero sudo[12973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 11 11:00:23 zero sudo[12973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 11:00:24 zero volumio[839]: info: MRS: Connected to cast device: Google-Cast-Group-3AD1498CE83441A584480E0DA82089CD May 11 11:00:24 zero systemd[1]: Started VolumioStreamingService. May 11 11:00:24 zero sudo[12973]: pam_unix(sudo:session): session closed for user root May 11 11:00:24 zero volumio[839]: info: MRS: volumioStreaming STARTED May 11 11:00:24 zero volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 11:00:24 zero volumio[839]: TypeError: Cannot read property 'on' of undefined May 11 11:00:24 zero volumio[839]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29798 May 11 11:00:24 zero volumio[839]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 May 11 11:00:24 zero volumio[839]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 May 11 11:00:24 zero volumio[839]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) May 11 11:00:24 zero volumio[839]: at fn.emit (events.js:327:22) May 11 11:00:24 zero volumio[839]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) May 11 11:00:24 zero volumio[839]: at Channel.emit (events.js:315:20) May 11 11:00:24 zero volumio[839]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) May 11 11:00:24 zero volumio[839]: at Client.emit (events.js:327:22) May 11 11:00:24 zero volumio[839]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) May 11 11:00:24 zero volumio[839]: at PacketStreamWrapper.emit (events.js:315:20) May 11 11:00:24 zero volumio[839]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) May 11 11:00:24 zero volumio[839]: at TLSSocket.emit (events.js:315:20) May 11 11:00:24 zero volumio[839]: at emitReadable_ (internal/streams/readable.js:569:12) May 11 11:00:24 zero volumio[839]: at processTicksAndRejections (internal/process/task_queues.js:79:21) May 11 11:00:24 zero volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 11:00:24 zero sudo[12977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 11 11:00:24 zero sudo[12977]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 11:00:24 zero sudo[12977]: pam_unix(sudo:session): session closed for user root May 11 11:00:29 zero sudo[13002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 10:59 May 11 11:00:29 zero sudo[13002]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"