-- Logs begin at Thu 2019-02-14 23:11:59 NZDT, end at Mon 2024-04-01 10:21:38 NZDT. -- Apr 01 10:20:45 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 01 10:20:45 volumio volumio[757]: info: MRS: Starting cast device: Chromecast-9434430b67ebd9f438239411c37364b0 Apr 01 10:20:45 volumio volumio[757]: info: Enabled audio output: Chromecast-9434430b67ebd9f438239411c37364b0 Apr 01 10:20:45 volumio volumio[757]: info: MRS: Setting this device as Streaming Server Apr 01 10:20:45 volumio volumio[757]: info: Apr 01 10:20:45 volumio volumio[757]: [1711920045727] ---------------------------- MRS: Setting Streaming Server Apr 01 10:20:45 volumio volumio[757]: info: MRS: enable multiroom server output Apr 01 10:20:45 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioMultiRoom Apr 01 10:20:45 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Apr 01 10:20:45 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 01 10:20:45 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 01 10:20:45 volumio volumio[757]: info: MRS: STARTING volumioStreaming Apr 01 10:20:45 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 01 10:20:45 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:20:45 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Apr 01 10:20:45 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 01 10:20:45 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:20:45 volumio systemd[1]: Started VolumioStreamingService. Apr 01 10:20:45 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Apr 01 10:20:45 volumio volumio[757]: info: MRS: volumioStreaming STARTED Apr 01 10:20:45 volumio sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 01 10:20:45 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:20:45 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:08 volumio volumio[757]: info: CoreCommandRouter::volumioPlay Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::play index undefined Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 01 10:21:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::startPlaybackTimer Apr 01 10:21:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Apr 01 10:21:08 volumio volumio[757]: info: [1711920068358] ControllerTidal::resume Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 01 10:21:08 volumio volumio[757]: info: ControllerMpd::resume Apr 01 10:21:08 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand play Apr 01 10:21:08 volumio volumio[757]: info: Apr 01 10:21:08 volumio volumio[757]: ---------------------------- MPD announces state update: player Apr 01 10:21:08 volumio volumio[757]: info: sendMpdCommand play took 14 milliseconds Apr 01 10:21:08 volumio volumio[757]: info: ControllerMpd::getState Apr 01 10:21:08 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Apr 01 10:21:08 volumio volumio[757]: info: sendMpdCommand status took 1 milliseconds Apr 01 10:21:08 volumio volumio[757]: verbose: ControllerMpd::parseState Apr 01 10:21:08 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 01 10:21:08 volumio volumio[757]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 01 10:21:08 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Apr 01 10:21:08 volumio volumio[757]: info: ControllerMpd::pushState Apr 01 10:21:08 volumio volumio[757]: info: CoreCommandRouter::servicePushState Apr 01 10:21:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Apr 01 10:21:08 volumio volumio[757]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34228,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1637 Kbps","isStreaming":false,"title":"68631721&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/68631721&soundQuality=HI_RES","trackType":"tidal"} Apr 01 10:21:08 volumio volumio[757]: verbose: CURRENT POSITION 0 Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::syncState stateService play Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus pause Apr 01 10:21:08 volumio volumio[757]: info: CoreStateMachine::pushState Apr 01 10:21:08 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 10:21:08 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Apr 01 10:21:08 volumio volumio[757]: info: MRS: Pushing multiroomSync output update for this device Apr 01 10:21:08 volumio volumio[757]: info: MRS: Pushing multiroomSync output Apr 01 10:21:08 volumio volumio[757]: info: ------------------------------ 24ms Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 01 10:21:16 volumio volumio[757]: xcb_connection_has_error() returned true Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 01 10:21:16 volumio volumio[757]: info: Getting Alsa Cards List without I2S DAC Apr 01 10:21:16 volumio volumio[757]: xcb_connection_has_error() returned true Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 01 10:21:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 10:21:16 volumio volumio[757]: xcb_connection_has_error() returned true Apr 01 10:21:17 volumio volumio[757]: xcb_connection_has_error() returned true Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 01 10:21:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 01 10:21:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Apr 01 10:21:35 volumio volumio[757]: info: MRS: Setting this device as single Apr 01 10:21:35 volumio volumio[757]: info: Apr 01 10:21:35 volumio volumio[757]: [1711920095493] ---------------------------- Setting Multiroom Single Apr 01 10:21:35 volumio volumio[757]: info: MRS: disable multiroom output Apr 01 10:21:35 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioDiscard Apr 01 10:21:35 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Apr 01 10:21:36 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 01 10:21:36 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Apr 01 10:21:36 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Apr 01 10:21:36 volumio volumio[757]: info: MRS: STOPPING volumioStreaming Apr 01 10:21:36 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 01 10:21:36 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:21:36 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream3.ts /tmp/hls/stream4.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Apr 01 10:21:36 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:21:36 volumio systemd[1]: Stopping VolumioStreamingService... Apr 01 10:21:36 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Apr 01 10:21:36 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:36 volumio systemd[1]: volumioStreaming.service: Succeeded. Apr 01 10:21:36 volumio systemd[1]: Stopped VolumioStreamingService. Apr 01 10:21:36 volumio volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 01 10:21:36 volumio volumio[757]: info: MRS: Removed streaming files Apr 01 10:21:37 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:37 volumio volumio[757]: info: MRS: volumioStreaming STOPPED Apr 01 10:21:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 01 10:21:37 volumio volumio[757]: info: MRS: Starting cast device: Onkyo-TX-RZ50-09df88c869d17a47ce8d828f2fae4e2b Apr 01 10:21:37 volumio volumio[757]: info: Enabled audio output: Onkyo-TX-RZ50-09df88c869d17a47ce8d828f2fae4e2b Apr 01 10:21:37 volumio volumio[757]: info: MRS: Setting this device as Streaming Server Apr 01 10:21:37 volumio volumio[757]: info: Apr 01 10:21:37 volumio volumio[757]: [1711920097906] ---------------------------- MRS: Setting Streaming Server Apr 01 10:21:37 volumio volumio[757]: info: MRS: enable multiroom server output Apr 01 10:21:37 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioMultiRoom Apr 01 10:21:37 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Apr 01 10:21:37 volumio volumio[757]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 01 10:21:37 volumio volumio[757]: info: MRS: Connected to cast device: Onkyo-TX-RZ50-09df88c869d17a47ce8d828f2fae4e2b Apr 01 10:21:37 volumio volumio[757]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 01 10:21:37 volumio volumio[757]: info: MRS: STARTING volumioStreaming Apr 01 10:21:38 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 01 10:21:38 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:21:38 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:38 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 01 10:21:38 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:21:38 volumio systemd[1]: Starting Cleanup of Temporary Directories... Apr 01 10:21:38 volumio volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 01 10:21:38 volumio systemd[1]: Started VolumioStreamingService. Apr 01 10:21:38 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:38 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 01 10:21:38 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:21:38 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Apr 01 10:21:38 volumio volumio[757]: info: MRS: volumioStreaming STARTED Apr 01 10:21:38 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root Apr 01 10:21:38 volumio systemd[1]: Started Cleanup of Temporary Directories. Apr 01 10:21:38 volumio volumio[757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 10:21:38 volumio volumio[757]: TypeError: Cannot read property 'on' of undefined Apr 01 10:21:38 volumio volumio[757]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29162 Apr 01 10:21:38 volumio volumio[757]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Apr 01 10:21:38 volumio volumio[757]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Apr 01 10:21:38 volumio volumio[757]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Apr 01 10:21:38 volumio volumio[757]: at fn.emit (events.js:327:22) Apr 01 10:21:38 volumio volumio[757]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Apr 01 10:21:38 volumio volumio[757]: at Channel.emit (events.js:315:20) Apr 01 10:21:38 volumio volumio[757]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Apr 01 10:21:38 volumio volumio[757]: at Client.emit (events.js:327:22) Apr 01 10:21:38 volumio volumio[757]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Apr 01 10:21:38 volumio volumio[757]: at PacketStreamWrapper.emit (events.js:315:20) Apr 01 10:21:38 volumio volumio[757]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Apr 01 10:21:38 volumio volumio[757]: at TLSSocket.emit (events.js:315:20) Apr 01 10:21:38 volumio volumio[757]: at emitReadable_ (internal/streams/readable.js:569:12) Apr 01 10:21:38 volumio volumio[757]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Apr 01 10:21:38 volumio volumio[757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 10:21:38 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 10:20 Apr 01 10:21:38 volumio sudo[1909]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"