-- Logs begin at Thu 2019-02-14 10:12:03 GMT, end at Sun 2024-04-28 16:37:12 BST. -- Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 16:36:29 musicserver volumio[828]: info: Discovery: Getting this device information Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:29 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 16:36:29 musicserver volumio[828]: verbose: New Socket.io Connection to 192.168.86.72 from 192.168.86.39 UA: okhttp/4.9.2 Total Clients: 5 Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:29 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 28 16:36:29 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 28 16:36:31 musicserver kernel: hwmon hwmon1: Undervoltage detected! Apr 28 16:36:32 musicserver sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 16:36:32 musicserver sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:36:32 musicserver sudo[1444]: pam_unix(sudo:session): session closed for user root Apr 28 16:36:32 musicserver sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 16:36:32 musicserver sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:36:32 musicserver sudo[1447]: pam_unix(sudo:session): session closed for user root Apr 28 16:36:32 musicserver volumio[828]: verbose: New Socket.io Connection to 192.168.86.72 from 192.168.86.39 UA: okhttp/4.9.2 Total Clients: 6 Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:32 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 28 16:36:32 musicserver volumio[828]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 28 16:36:32 musicserver volumio[828]: info: Received Get System Info Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 16:36:32 musicserver volumio[828]: info: Discovery: Getting this device information Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:32 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:32 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:32 musicserver volumio[828]: info: Listing playlists Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 28 16:36:32 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::volumioGetQueue Apr 28 16:36:34 musicserver volumio[828]: info: CoreStateMachine::getQueue Apr 28 16:36:34 musicserver volumio[828]: info: CorePlayQueue::getQueue Apr 28 16:36:34 musicserver volumio[828]: info: Executing endpoint metavolumio Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 16:36:34 musicserver volumio[828]: info: Executing endpoint metavolumio Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 28 16:36:34 musicserver volumio[828]: info: Received Get System Info Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 16:36:34 musicserver volumio[828]: info: Discovery: Getting this device information Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:34 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:34 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 16:36:35 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 28 16:36:35 musicserver volumio[828]: info: Received Get System Info Apr 28 16:36:35 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 16:36:35 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 16:36:35 musicserver volumio[828]: info: Discovery: Getting this device information Apr 28 16:36:35 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:35 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:35 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 16:36:38 musicserver kernel: hwmon hwmon1: Voltage normalised Apr 28 16:36:41 musicserver volumio[828]: error: Failed request for metavolumio API Apr 28 16:36:41 musicserver volumio[828]: error: Failed request for metavolumio API Apr 28 16:36:46 musicserver kernel: hwmon hwmon1: Undervoltage detected! Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 28 16:36:50 musicserver volumio[828]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 28 16:36:50 musicserver volumio[828]: info: Received Get System Version Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 16:36:50 musicserver volumio[828]: info: Received Get System Info Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 16:36:50 musicserver volumio[828]: info: Discovery: Getting this device information Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::volumioGetState Apr 28 16:36:50 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:36:50 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 16:36:50 musicserver kernel: hwmon hwmon1: Voltage normalised Apr 28 16:36:52 musicserver volumio[828]: Searching plugin music_service/mpd Apr 28 16:36:52 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 28 16:36:52 musicserver volumio[828]: info: All search sources collected, pushing search results Apr 28 16:36:56 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 16:36:56 musicserver volumio[828]: info: CURURI: artists://Rush Apr 28 16:36:56 musicserver volumio[828]: info: Preload queue cleared Apr 28 16:36:56 musicserver volumio[828]: info: Preload queue cleared Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/02. Red Barchetta.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/03. YYZ.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/04. Limelight.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/05. The Camera Eye.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/06. Witch Hunt.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/07. Vital Signs.flac Apr 28 16:36:56 musicserver volumio[828]: info: Preloading song: music-library/USB/WD_MyBook_3TB/Music/Various Artists - MOFI Sampler 2 (MFSL SACD) [FLAC]/10 - Rush - Tom Sawyer.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F01.%20Tom%20Sawyer.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/02. Red Barchetta.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F02.%20Red%20Barchetta.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/02. Red Barchetta.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/03. YYZ.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F03.%20YYZ.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/03. YYZ.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/04. Limelight.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F04.%20Limelight.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/04. Limelight.flac Apr 28 16:36:57 musicserver volumio[828]: info: Executing endpoint getSimilarArtists Apr 28 16:36:57 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/05. The Camera Eye.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F05.%20The%20Camera%20Eye.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/05. The Camera Eye.flac Apr 28 16:36:57 musicserver volumio[828]: info: Executing endpoint metavolumio Apr 28 16:36:57 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/06. Witch Hunt.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F06.%20Witch%20Hunt.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/06. Witch Hunt.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/07. Vital Signs.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Moving%20Pictures/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FRush%20-%20Moving%20Pictures%20%5BFLAC%2024-96%5D%2F07.%20Vital%20Signs.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/07. Vital Signs.flac Apr 28 16:36:57 musicserver volumio[828]: info: Exploding uri music-library/USB/WD_MyBook_3TB/Music/Various Artists - MOFI Sampler 2 (MFSL SACD) [FLAC]/10 - Rush - Tom Sawyer.flac in service mpd Apr 28 16:36:57 musicserver volumio[828]: info: ALBUMART /albumart?cacheid=335&web=Rush/Ultradisc%20II%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FWD_MyBook_3TB%2FMusic%2FVarious%20Artists%20-%20MOFI%20Sampler%202%20(MFSL%20SACD)%20%5BFLAC%5D%2F10%20-%20Rush%20-%20Tom%20Sawyer.flac&metadata=false Apr 28 16:36:57 musicserver volumio[828]: info: URI /mnt/USB/WD_MyBook_3TB/Music/Various Artists - MOFI Sampler 2 (MFSL SACD) [FLAC]/10 - Rush - Tom Sawyer.flac Apr 28 16:36:58 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 16:36:58 musicserver volumio[828]: info: CURURI: artists://Rush/Moving%20Pictures Apr 28 16:36:58 musicserver volumio[828]: info: Preload queue cleared Apr 28 16:36:58 musicserver volumio[828]: info: Executing endpoint getSimilarAlbums Apr 28 16:36:58 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 28 16:36:59 musicserver volumio[828]: info: Executing endpoint metavolumio Apr 28 16:36:59 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 16:36:59 musicserver volumio[828]: info: Executing endpoint metavolumio Apr 28 16:36:59 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 16:37:01 musicserver kernel: hwmon hwmon1: Undervoltage detected! Apr 28 16:37:01 musicserver volumio[828]: info: Preload queue cleared Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::ClearQueue Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::stop Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::clearPlayQueue Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::saveQueue Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioPushQueue Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::addQueueItems Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::addQueueItems Apr 28 16:37:01 musicserver volumio[828]: info: Preload queue cleared Apr 28 16:37:01 musicserver volumio[828]: info: Adding Item to queue: artists://Rush/Moving%20Pictures Apr 28 16:37:01 musicserver volumio[828]: info: Exploding uri artists://Rush/Moving%20Pictures in service mpd Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioPushQueue Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::saveQueue Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::updateTrackBlock Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrackBlock Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioPlay Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::play index 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::stop Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::play index undefined Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::startPlaybackTimer Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::clearAddPlayTracks USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand stop took 2 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand clear Apr 28 16:37:01 musicserver volumio[828]: info: Apr 28 16:37:01 musicserver volumio[828]: ---------------------------- MPD announces system playlist update Apr 28 16:37:01 musicserver volumio[828]: info: Ignoring MPD Status Update Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand clear took 15 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand add "USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac" Apr 28 16:37:01 musicserver volumio[828]: error: updateQueue error: null Apr 28 16:37:01 musicserver volumio[828]: info: Apr 28 16:37:01 musicserver volumio[828]: ---------------------------- MPD announces system playlist update Apr 28 16:37:01 musicserver volumio[828]: info: Ignoring MPD Status Update Apr 28 16:37:01 musicserver volumio[828]: info: ------------------------------ 15ms Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand add "USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac" took 11 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand play Apr 28 16:37:01 musicserver volumio[828]: error: Upnp client error: Error: This socket has been ended by the other party Apr 28 16:37:01 musicserver volumio[828]: info: ------------------------------ 17ms Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand play took 14 milliseconds Apr 28 16:37:01 musicserver volumio[828]: info: Apr 28 16:37:01 musicserver volumio[828]: ---------------------------- MPD announces state update: player Apr 28 16:37:01 musicserver volumio[828]: info: ControllerMpd::getState Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand status Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand status took 10 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::parseState Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 16:37:01 musicserver volumio[828]: info: Apr 28 16:37:01 musicserver volumio[828]: ---------------------------- MPD announces state update: player Apr 28 16:37:01 musicserver volumio[828]: info: ControllerMpd::getState Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand status Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::parseTrackInfo Apr 28 16:37:01 musicserver volumio[828]: info: ControllerMpd::pushState Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::servicePushState Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tom Sawyer","artist":"Rush","album":"Moving Pictures","uri":"USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac","trackType":"flac"} Apr 28 16:37:01 musicserver volumio[828]: verbose: CURRENT POSITION 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::syncState stateService play Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::syncState currentStatus stop Apr 28 16:37:01 musicserver volumio[828]: info: ------------------------------ 45ms Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand status took 22 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::parseState Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 16:37:01 musicserver volumio[828]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 28 16:37:01 musicserver volumio[828]: verbose: ControllerMpd::parseTrackInfo Apr 28 16:37:01 musicserver volumio[828]: info: ControllerMpd::pushState Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::servicePushState Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tom Sawyer","artist":"Rush","album":"Moving Pictures","uri":"USB/WD_MyBook_3TB/Music/Rush - Moving Pictures [FLAC 24-96]/01. Tom Sawyer.flac","trackType":"flac"} Apr 28 16:37:01 musicserver volumio[828]: verbose: CURRENT POSITION 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::syncState stateService play Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::syncState currentStatus play Apr 28 16:37:01 musicserver volumio[828]: info: Received an update from plugin. extracting info from payload Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::pushState Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioPushState Apr 28 16:37:01 musicserver volumio[828]: info: MRS: Pushing multiroomSync output update for this device Apr 28 16:37:01 musicserver volumio[828]: info: MRS: Pushing multiroomSync output Apr 28 16:37:01 musicserver volumio[828]: info: CoreStateMachine::pushState Apr 28 16:37:01 musicserver volumio[828]: info: CorePlayQueue::getTrack 0 Apr 28 16:37:01 musicserver volumio[828]: info: CoreCommandRouter::volumioPushState Apr 28 16:37:01 musicserver volumio[828]: info: MRS: Pushing multiroomSync output update for this device Apr 28 16:37:01 musicserver volumio[828]: info: MRS: Pushing multiroomSync output Apr 28 16:37:01 musicserver volumio[828]: info: ------------------------------ 115ms Apr 28 16:37:05 musicserver volumio[828]: error: Failed request for metavolumio API Apr 28 16:37:05 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 28 16:37:05 musicserver volumio[828]: info: MRS: Starting browser stream Apr 28 16:37:05 musicserver volumio[828]: info: MRS: Setting this device as Streaming Server Apr 28 16:37:05 musicserver volumio[828]: info: Apr 28 16:37:05 musicserver volumio[828]: [1714318625943] ---------------------------- MRS: Setting Streaming Server Apr 28 16:37:05 musicserver volumio[828]: info: Enabled audio output: browserPlayback Apr 28 16:37:05 musicserver volumio[828]: info: MRS: enable multiroom server output Apr 28 16:37:05 musicserver volumio[828]: info: MRS: Set multiroom target PCM to volumioMultiRoom Apr 28 16:37:06 musicserver volumio[828]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Apr 28 16:37:06 musicserver volumio[828]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 28 16:37:06 musicserver volumio[828]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 28 16:37:06 musicserver volumio[828]: info: MRS: STARTING volumioStreaming Apr 28 16:37:06 musicserver sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 28 16:37:06 musicserver sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:06 musicserver sudo[1472]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:06 musicserver sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 28 16:37:06 musicserver sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:06 musicserver systemd[1]: Started VolumioStreamingService. Apr 28 16:37:06 musicserver sudo[1474]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:06 musicserver sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 28 16:37:06 musicserver sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:06 musicserver volumio[828]: info: MRS: volumioStreaming STARTED Apr 28 16:37:06 musicserver sudo[1478]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:12 musicserver volumio[828]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 28 16:37:12 musicserver volumio[828]: info: MRS: Starting cast device: Google-Cast-Group-46a78b70729e44db97b2c41ac1663d14 Apr 28 16:37:12 musicserver volumio[828]: info: Enabled audio output: Google-Cast-Group-46a78b70729e44db97b2c41ac1663d14 Apr 28 16:37:12 musicserver volumio[828]: info: MRS: Setting this device as Streaming Server Apr 28 16:37:12 musicserver volumio[828]: info: Apr 28 16:37:12 musicserver volumio[828]: [1714318632211] ---------------------------- MRS: Setting Streaming Server Apr 28 16:37:12 musicserver volumio[828]: info: MRS: enable multiroom server output Apr 28 16:37:12 musicserver volumio[828]: info: MRS: Set multiroom target PCM to volumioMultiRoom Apr 28 16:37:12 musicserver volumio[828]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Apr 28 16:37:12 musicserver volumio[828]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 28 16:37:12 musicserver volumio[828]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 28 16:37:12 musicserver volumio[828]: info: MRS: STARTING volumioStreaming Apr 28 16:37:12 musicserver sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream2.ts /tmp/hls/stream3.ts /tmp/hls/stream4.ts Apr 28 16:37:12 musicserver sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:12 musicserver sudo[1500]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:12 musicserver sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 28 16:37:12 musicserver sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:12 musicserver systemd[1]: Stopping VolumioStreamingService... Apr 28 16:37:12 musicserver systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Apr 28 16:37:12 musicserver volumio[828]: info: MRS: Connected to cast device: Google-Cast-Group-46a78b70729e44db97b2c41ac1663d14 Apr 28 16:37:12 musicserver systemd[1]: volumioStreaming.service: Succeeded. Apr 28 16:37:12 musicserver systemd[1]: Stopped VolumioStreamingService. Apr 28 16:37:12 musicserver volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 28 16:37:12 musicserver volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 28 16:37:12 musicserver systemd[1]: Started VolumioStreamingService. Apr 28 16:37:12 musicserver sudo[1503]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:12 musicserver volumio[828]: info: MRS: volumioStreaming STARTED Apr 28 16:37:12 musicserver sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 28 16:37:12 musicserver sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 16:37:12 musicserver sudo[1506]: pam_unix(sudo:session): session closed for user root Apr 28 16:37:12 musicserver volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 16:37:12 musicserver volumio[828]: TypeError: Cannot read property 'on' of undefined Apr 28 16:37:12 musicserver volumio[828]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29162 Apr 28 16:37:12 musicserver volumio[828]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Apr 28 16:37:12 musicserver volumio[828]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Apr 28 16:37:12 musicserver volumio[828]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Apr 28 16:37:12 musicserver volumio[828]: at fn.emit (events.js:327:22) Apr 28 16:37:12 musicserver volumio[828]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Apr 28 16:37:12 musicserver volumio[828]: at Channel.emit (events.js:315:20) Apr 28 16:37:12 musicserver volumio[828]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Apr 28 16:37:12 musicserver volumio[828]: at Client.emit (events.js:327:22) Apr 28 16:37:12 musicserver volumio[828]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Apr 28 16:37:12 musicserver volumio[828]: at PacketStreamWrapper.emit (events.js:315:20) Apr 28 16:37:12 musicserver volumio[828]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Apr 28 16:37:12 musicserver volumio[828]: at TLSSocket.emit (events.js:315:20) Apr 28 16:37:12 musicserver volumio[828]: at emitReadable_ (internal/streams/readable.js:569:12) Apr 28 16:37:12 musicserver volumio[828]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Apr 28 16:37:12 musicserver volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 16:37:12 musicserver sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 16:36 Apr 28 16:37:12 musicserver sudo[1518]: 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"