-- Logs begin at Sat 2026-02-21 10:23:35 GMT, end at Sat 2026-02-21 10:26:47 GMT. -- Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:00 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 10:25:00 volumio volumio[1344]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 21 10:25:00 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:00 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:00 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:00 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:00 volumio volumio[1344]: info: Listing playlists Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 10:25:00 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:00 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 10:25:01 volumio volumio[1344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:01 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:01 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:01 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:01 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.85:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:25:01 volumio volumio[1344]: error: Error getting DISCID: Error: Command failed: /usr/bin/abcde -a cddb -N -d /dev/sr0 Feb 21 10:25:01 volumio volumio[1344]: info: Could not get CDDB Entry for unknown DiscID Feb 21 10:25:01 volumio volumio[1344]: error: GETCD INFO Cannot read CDDB file Error: EISDIR: illegal operation on a directory, read Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 10:25:01 volumio volumio[1344]: info: [1771669501136] CoreMusicLibrary::Adding element Audio CD Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Bandcamp Discover Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Calm Radio Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Mixcloud Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source SoundCloud Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Mother Earth Radio Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Pandora Radio Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Podcast Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Radio Paradise Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source LastFM Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Randomizer Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Plex Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source Audio CD Feb 21 10:25:01 volumio volumio[1344]: Cannot find translation for source QOBUZ Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:25:01 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:01 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:01 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:01 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:25:02 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:02 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:02 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:02 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:02 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:02 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:02 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:05 volumio volumio[1344]: info: BOOT COMPLETED Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 10:25:06 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:25:09 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 10:25:17 volumio sudo[3056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 10:25:17 volumio sudo[3056]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:17 volumio sudo[3056]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:17 volumio sudo[3059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 10:25:17 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:17 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:17 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.85 from 192.168.0.70 UA: Mozilla/5.0 (Linux; Android 13; M2101K6G Build/TKQ1.221013.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 10:25:17 volumio volumio[1344]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 10:25:17 volumio volumio[1344]: info: Received Get System Version Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:25:17 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:17 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:17 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:17 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 10:25:17 volumio volumio[1344]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 21 10:25:17 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:17 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:17 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:17 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:17 volumio volumio[1344]: info: Listing playlists Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 10:25:17 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 21 10:25:18 volumio volumio[1344]: info: Received Get System Version Feb 21 10:25:18 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:25:19 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:19 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:19 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:19 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 10:25:20 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:25:20 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:20 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:20 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:20 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:20 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:20 volumio volumio[1344]: info: CorePlayQueue::getTrack 0 Feb 21 10:25:20 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:22 volumio volumio[1344]: info: CoreCommandRouter::volumioGetQueue Feb 21 10:25:22 volumio volumio[1344]: info: CoreStateMachine::getQueue Feb 21 10:25:22 volumio volumio[1344]: info: CorePlayQueue::getQueue Feb 21 10:25:24 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:25:24 volumio volumio[1344]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 10:25:24 volumio volumio[1344]: info: CoreStateMachine::addQueueItems Feb 21 10:25:24 volumio volumio[1344]: info: CorePlayQueue::addQueueItems Feb 21 10:25:24 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:25:24 volumio volumio[1344]: info: Adding Item to queue: cdaudio/track18 Feb 21 10:25:24 volumio volumio[1344]: info: Exploding uri cdaudio/track18 in service cd_controller Feb 21 10:25:27 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 10:25:30 volumio volumio[1344]: info: CoreCommandRouter::volumioPushQueue Feb 21 10:25:30 volumio volumio[1344]: info: CorePlayQueue::saveQueue Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:25:30 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:25:30 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::play index 16 Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::stop Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:30 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:30 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:25:30 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:30 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:25:30 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:25:30 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:25:30 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///19 Feb 21 10:25:30 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:25:30 volumio volumio[1344]: info: sendMpdCommand stop took 4 milliseconds Feb 21 10:25:30 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:25:30 volumio volumio[1344]: info: Feb 21 10:25:30 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:25:30 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:25:30 volumio volumio[1344]: info: sendMpdCommand clear took 1 milliseconds Feb 21 10:25:30 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///19" Feb 21 10:25:30 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:25:30 volumio volumio[1344]: info: ------------------------------ 3ms Feb 21 10:25:30 volumio volumio[1344]: info: sendMpdCommand add "cdda:///19" took 4 milliseconds Feb 21 10:25:30 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:25:30 volumio volumio[1344]: info: Feb 21 10:25:30 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:25:30 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:25:30 volumio volumio[1344]: info: sendMpdCommand play took 3 milliseconds Feb 21 10:25:30 volumio volumio[1344]: info: ------------------------------ 2ms Feb 21 10:25:33 volumio volumio[1344]: info: Feb 21 10:25:33 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:25:33 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:25:33 volumio volumio[1344]: info: Feb 21 10:25:33 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:25:33 volumio volumio[1344]: info: sendMpdCommand status took 13 milliseconds Feb 21 10:25:33 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:25:33 volumio volumio[1344]: info: sendMpdCommand status took 5 milliseconds Feb 21 10:25:33 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:25:33 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"19","artist":null,"album":null,"uri":"cdda:///19","trackType":"CD Audio"} Feb 21 10:25:33 volumio volumio[1344]: verbose: CURRENT POSITION 16 Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus stop Feb 21 10:25:33 volumio volumio[1344]: info: ------------------------------ 26ms Feb 21 10:25:33 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 10:25:33 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:25:33 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"19","artist":null,"album":null,"uri":"cdda:///19","trackType":"CD Audio"} Feb 21 10:25:33 volumio volumio[1344]: verbose: CURRENT POSITION 16 Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus play Feb 21 10:25:33 volumio volumio[1344]: info: Received an update from plugin. extracting info from payload Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:33 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:33 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:33 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:33 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:33 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:33 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:33 volumio volumio[1344]: info: ------------------------------ 74ms Feb 21 10:25:33 volumio volumio[1344]: info: [LastFM] Current track has sufficient metadata: title (Twenty Years) and artist (Placebo) passed on explicitly Feb 21 10:25:33 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:25:33 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:25:33 volumio sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:25:33 volumio sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:33 volumio systemd[1]: Started peppyspectrum Daemon. Feb 21 10:25:33 volumio sudo[3569]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:33 volumio sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:25:33 volumio sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:33 volumio sudo[3574]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:33 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:25:33 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:25:34 volumio volumio[1344]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Twenty Years","mbid":"addeddd8-e236-4ce7-b389-ce4c6232abff","url":"https://www.last.fm/music/Placebo/_/Twenty+Years","duration":"259000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"284840","playcount":"2345964","artist":{"name":"Placebo","mbid":"847e8284-8582-4b0e-9c26-b042a4f49e57","url":"https://www.last.fm/music/Placebo"},"album":{"artist":"Placebo","title":"Once More With Feeling - Singles 1996-2004","url":"https://www.last.fm/music/Placebo/Once+More+With+Feeling+-+Singles+1996-2004","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"extralarge"}}]},"userplaycount":"12","userloved":"0","toptags":""}} Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:34 volumio volumio[3571]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:35 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:35 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:35 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:35 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:35 volumio volumio[1344]: info: Executing endpoint metavolumio Feb 21 10:25:35 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 10:25:37 volumio volumio[1344]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 10:25:37 volumio volumio[1344]: info: Received Get System Version Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:25:37 volumio volumio[1344]: info: Received Get System Info Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:37 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:37 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:47 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 21 10:25:47 volumio volumio[1344]: info: MRS: Starting sonos device: [object Object] Feb 21 10:25:47 volumio volumio[1344]: info: Enabled audio output: RINCON_000E5852128801400:3092021832 Feb 21 10:25:47 volumio volumio[1344]: info: MRS: Setting this device as Streaming Server Feb 21 10:25:47 volumio volumio[1344]: info: Feb 21 10:25:47 volumio volumio[1344]: [1771669547074] ---------------------------- MRS: Setting Streaming Server Feb 21 10:25:47 volumio volumio[1344]: info: MRS: enable multiroom server output Feb 21 10:25:47 volumio volumio[1344]: info: MRS: Set multiroom target PCM to volumioMultiRoom Feb 21 10:25:47 volumio volumio[1344]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Feb 21 10:25:47 volumio volumio[1344]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 21 10:25:47 volumio volumio[1344]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Feb 21 10:25:47 volumio volumio[1344]: info: MRS: STARTING volumioStreaming Feb 21 10:25:47 volumio sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 21 10:25:47 volumio sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:47 volumio sudo[3661]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:47 volumio sudo[3664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 21 10:25:47 volumio sudo[3664]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:47 volumio systemd[1]: Started VolumioStreamingService. Feb 21 10:25:47 volumio sudo[3664]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:47 volumio volumio[1344]: info: MRS: volumioStreaming STARTED Feb 21 10:25:47 volumio sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 21 10:25:47 volumio sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:47 volumio sudo[3667]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:51 volumio volumio[1344]: info: MRS: Starting sonos device: uuid:RINCON_000E5852128801400 Feb 21 10:25:51 volumio volumio[1344]: info: Refreshing Cached IP Addresses Feb 21 10:25:51 volumio sudo[3675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 10:25:51 volumio sudo[3675]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:51 volumio sudo[3675]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:51 volumio sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 10:25:51 volumio sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:51 volumio sudo[3678]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:52 volumio volumio[1344]: info: MRS: Started sonos device: uuid:RINCON_000E5852128801400 Feb 21 10:25:56 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::addQueueItems Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::addQueueItems Feb 21 10:25:56 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:25:56 volumio volumio[1344]: info: Adding Item to queue: cdaudio/track17 Feb 21 10:25:56 volumio volumio[1344]: info: Exploding uri cdaudio/track17 in service cd_controller Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPushQueue Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::saveQueue Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::play index 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::stop Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::stPlaybackTimer Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::serviceStop Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 16 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::serviceStop Feb 21 10:25:56 volumio volumio[1344]: info: ControllerMpd::stop Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:25:56 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:25:56 volumio sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:25:56 volumio sudo[3681]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:56 volumio systemd[1]: Stopping peppyspectrum Daemon... Feb 21 10:25:56 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Feb 21 10:25:56 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 10:25:56 volumio systemd[1]: peppyspectrum.service: Succeeded. Feb 21 10:25:56 volumio systemd[1]: Stopped peppyspectrum Daemon. Feb 21 10:25:56 volumio sudo[3681]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:56 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:25:56 volumio volumio[1344]: info: Feb 21 10:25:56 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand stop took 300 milliseconds Feb 21 10:25:56 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///18 Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand status took 4 milliseconds Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand stop took 2 milliseconds Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:25:56 volumio volumio[1344]: info: Feb 21 10:25:56 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:25:56 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand clear took 2 milliseconds Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///18" Feb 21 10:25:56 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"19","artist":null,"album":null,"uri":"cdda:///19","trackType":"CD Audio"} Feb 21 10:25:56 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::syncState stateService stop Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus stop Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: No code Feb 21 10:25:56 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:56 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:56 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:56 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:56 volumio volumio[1344]: info: ------------------------------ 79ms Feb 21 10:25:56 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:25:56 volumio volumio[1344]: info: Feb 21 10:25:56 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:25:56 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:25:56 volumio volumio[1344]: info: ------------------------------ 72ms Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand add "cdda:///18" took 70 milliseconds Feb 21 10:25:56 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:25:56 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:25:56 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:25:56 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:25:56 volumio sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:25:56 volumio sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:56 volumio sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:25:56 volumio sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:56 volumio sudo[3684]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:56 volumio sudo[3686]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:56 volumio sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:25:56 volumio sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:56 volumio volumio[1344]: info: ------------------------------ 92ms Feb 21 10:25:56 volumio volumio[1344]: info: sendMpdCommand play took 92 milliseconds Feb 21 10:25:56 volumio sudo[3690]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:56 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:25:56 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:25:56 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:57 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:57 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:57 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:25:57 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:57 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:25:57 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.85:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:25:57 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:25:59 volumio volumio[1344]: info: MRS: STARTING volumioStreaming Feb 21 10:25:59 volumio sudo[3707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Feb 21 10:25:59 volumio sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:59 volumio sudo[3707]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:59 volumio sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 21 10:25:59 volumio sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:59 volumio systemd[1]: Stopping VolumioStreamingService... Feb 21 10:25:59 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 21 10:25:59 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 10:25:59 volumio volumio[1344]: info: Feb 21 10:25:59 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:25:59 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:25:59 volumio volumio[1344]: info: Feb 21 10:25:59 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:25:59 volumio volumio[1344]: info: sendMpdCommand status took 2 milliseconds Feb 21 10:25:59 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:25:59 volumio volumio[1344]: info: sendMpdCommand status took 1 milliseconds Feb 21 10:25:59 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:25:59 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18","artist":null,"album":null,"uri":"cdda:///18","trackType":"CD Audio"} Feb 21 10:25:59 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus stop Feb 21 10:25:59 volumio volumio[1344]: info: ------------------------------ 8ms Feb 21 10:25:59 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 21 10:25:59 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:25:59 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18","artist":null,"album":null,"uri":"cdda:///18","trackType":"CD Audio"} Feb 21 10:25:59 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus play Feb 21 10:25:59 volumio volumio[1344]: info: Received an update from plugin. extracting info from payload Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:59 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:59 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:25:59 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:25:59 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:25:59 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:25:59 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:25:59 volumio volumio[1344]: info: ------------------------------ 72ms Feb 21 10:25:59 volumio volumio[1344]: info: [LastFM] Current track has sufficient metadata: title (I Do) and artist (Placebo) passed on explicitly Feb 21 10:25:59 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:25:59 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:25:59 volumio sudo[3713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:25:59 volumio sudo[3713]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:59 volumio sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:25:59 volumio sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:25:59 volumio systemd[1]: Started peppyspectrum Daemon. Feb 21 10:25:59 volumio sudo[3713]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:59 volumio sudo[3716]: pam_unix(sudo:session): session closed for user root Feb 21 10:25:59 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:25:59 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:25:59 volumio volumio[1344]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"I Do","mbid":"cc3dfb28-367a-4331-8cc9-860f33187a08","url":"https://www.last.fm/music/Placebo/_/I+Do","duration":"147000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"153871","playcount":"898384","artist":{"name":"Placebo","mbid":"847e8284-8582-4b0e-9c26-b042a4f49e57","url":"https://www.last.fm/music/Placebo"},"album":{"artist":"Placebo","title":"Once More With Feeling - Singles 1996-2004","url":"https://www.last.fm/music/Placebo/Once+More+With+Feeling+-+Singles+1996-2004","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/1e51565043e4442daf7065b423b98b4d.png","@":{"size":"extralarge"}}]},"userplaycount":"21","userloved":"0","toptags":""}} Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:25:59 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:00 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:00 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:00 volumio volumio[3718]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:01 volumio volumio[1344]: info: MRS: Starting sonos device: uuid:RINCON_000E5852128801400 Feb 21 10:26:01 volumio volumio[1344]: info: Refreshing Cached IP Addresses Feb 21 10:26:01 volumio sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 10:26:01 volumio sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:01 volumio sudo[3730]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:01 volumio sudo[3733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 10:26:01 volumio sudo[3733]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:01 volumio sudo[3733]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:01 volumio volumio[1344]: error: MRS: Error starting sonos device: uuid:RINCON_000E5852128801400:Error: upnp: statusCode 500 & upnpErrorCode s:ClientUPnPError800 Feb 21 10:26:05 volumio systemd[1]: volumioStreaming.service: Succeeded. Feb 21 10:26:05 volumio systemd[1]: Stopped VolumioStreamingService. Feb 21 10:26:05 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 10:26:05 volumio systemd[1]: Started VolumioStreamingService. Feb 21 10:26:05 volumio sudo[3710]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:05 volumio volumio[1344]: info: MRS: volumioStreaming STARTED Feb 21 10:26:05 volumio sudo[3737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 Feb 21 10:26:05 volumio sudo[3737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:05 volumio sudo[3737]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:31 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::addQueueItems Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::addQueueItems Feb 21 10:26:31 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:26:31 volumio volumio[1344]: info: Adding Item to queue: cdaudio/track17 Feb 21 10:26:31 volumio volumio[1344]: info: Exploding uri cdaudio/track17 in service cd_controller Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::volumioPushQueue Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::saveQueue Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::play index 17 Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::stop Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::stPlaybackTimer Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:31 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:31 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:31 volumio volumio[1344]: info: CoreStateMachine::serviceStop Feb 21 10:26:31 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:31 volumio volumio[1344]: info: CoreCommandRouter::serviceStop Feb 21 10:26:31 volumio volumio[1344]: info: ControllerMpd::stop Feb 21 10:26:31 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:26:31 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:26:31 volumio sudo[3793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:26:31 volumio sudo[3793]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:31 volumio systemd[1]: Stopping peppyspectrum Daemon... Feb 21 10:26:31 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Feb 21 10:26:31 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 10:26:31 volumio systemd[1]: peppyspectrum.service: Succeeded. Feb 21 10:26:31 volumio systemd[1]: Stopped peppyspectrum Daemon. Feb 21 10:26:31 volumio sudo[3793]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:31 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:26:32 volumio volumio[1344]: info: Feb 21 10:26:32 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand stop took 305 milliseconds Feb 21 10:26:32 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///18 Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand status took 4 milliseconds Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand stop took 2 milliseconds Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:26:32 volumio volumio[1344]: info: Feb 21 10:26:32 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:32 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand clear took 1 milliseconds Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///18" Feb 21 10:26:32 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18","artist":null,"album":null,"uri":"cdda:///18","trackType":"CD Audio"} Feb 21 10:26:32 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::syncState stateService stop Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus stop Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: No code Feb 21 10:26:32 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:32 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:32 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:32 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:32 volumio volumio[1344]: info: ------------------------------ 81ms Feb 21 10:26:32 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:26:32 volumio volumio[1344]: info: Feb 21 10:26:32 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:32 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:32 volumio volumio[1344]: info: ------------------------------ 77ms Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand add "cdda:///18" took 76 milliseconds Feb 21 10:26:32 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:26:32 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:26:32 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:26:32 volumio sudo[3796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:26:32 volumio sudo[3796]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:32 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status stop Feb 21 10:26:32 volumio sudo[3796]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:32 volumio sudo[3799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:26:32 volumio sudo[3799]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:32 volumio sudo[3802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 21 10:26:32 volumio sudo[3802]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:32 volumio volumio[1344]: info: ------------------------------ 77ms Feb 21 10:26:32 volumio volumio[1344]: info: sendMpdCommand play took 77 milliseconds Feb 21 10:26:32 volumio sudo[3799]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:32 volumio sudo[3802]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:32 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:26:32 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:26:32 volumio volumio[1344]: info: peppyspectrum Daemon Stop Feb 21 10:26:34 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::addQueueItems Feb 21 10:26:34 volumio volumio[1344]: info: CorePlayQueue::addQueueItems Feb 21 10:26:34 volumio volumio[1344]: info: Preload queue cleared Feb 21 10:26:34 volumio volumio[1344]: info: Adding Item to queue: cdaudio/track17 Feb 21 10:26:34 volumio volumio[1344]: info: Exploding uri cdaudio/track17 in service cd_controller Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::volumioPushQueue Feb 21 10:26:34 volumio volumio[1344]: info: CorePlayQueue::saveQueue Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::updateTrackBlock Feb 21 10:26:34 volumio volumio[1344]: info: CorePlayQueue::getTrackBlock Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::play index 17 Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::stop Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:34 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:34 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:26:34 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:26:34 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:26:34 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///18 Feb 21 10:26:34 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:26:34 volumio volumio[1344]: info: MRS: STARTING volumioStreaming Feb 21 10:26:34 volumio sudo[3805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts Feb 21 10:26:34 volumio sudo[3805]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:34 volumio sudo[3805]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:34 volumio sudo[3808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 21 10:26:34 volumio sudo[3808]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:34 volumio systemd[1]: Stopping VolumioStreamingService... Feb 21 10:26:34 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 21 10:26:34 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 10:26:34 volumio systemd[1]: volumioStreaming.service: Succeeded. Feb 21 10:26:34 volumio systemd[1]: Stopped VolumioStreamingService. Feb 21 10:26:34 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 10:26:34 volumio systemd[1]: Started VolumioStreamingService. Feb 21 10:26:34 volumio sudo[3808]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:34 volumio volumio[1344]: info: MRS: volumioStreaming STARTED Feb 21 10:26:34 volumio sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 21 10:26:34 volumio sudo[3811]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:34 volumio sudo[3811]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:35 volumio volumio[1344]: info: Feb 21 10:26:35 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand stop took 588 milliseconds Feb 21 10:26:35 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:26:35 volumio volumio[1344]: info: Feb 21 10:26:35 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:35 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand status took 2 milliseconds Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand clear took 2 milliseconds Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///18" Feb 21 10:26:35 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:26:35 volumio volumio[1344]: info: Feb 21 10:26:35 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:35 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:35 volumio volumio[1344]: info: ------------------------------ 3ms Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand add "cdda:///18" took 3 milliseconds Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:26:35 volumio volumio[1344]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 21 10:26:35 volumio volumio[1344]: info: ------------------------------ 9ms Feb 21 10:26:35 volumio volumio[1344]: info: ------------------------------ 4ms Feb 21 10:26:35 volumio volumio[1344]: info: sendMpdCommand play took 2 milliseconds Feb 21 10:26:35 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:26:35 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:26:35 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:35 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:35 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:26:35 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:35 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:26:35 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:26:35 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///18 Feb 21 10:26:35 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:26:37 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:37 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:26:37 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:26:37 volumio volumio[1344]: info: Discovery: Getting this device information Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:37 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:26:37 volumio volumio[1344]: verbose: New Socket.io Connection to 192.168.0.85:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:26:37 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:26:37 volumio volumio[1344]: info: Feb 21 10:26:37 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand stop took 2129 milliseconds Feb 21 10:26:37 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:26:37 volumio volumio[1344]: info: Feb 21 10:26:37 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:37 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand status took 2 milliseconds Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand clear took 2 milliseconds Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///18" Feb 21 10:26:37 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:26:37 volumio volumio[1344]: info: Feb 21 10:26:37 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:37 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:37 volumio volumio[1344]: info: ------------------------------ 4ms Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand add "cdda:///18" took 2 milliseconds Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:37 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:26:37 volumio volumio[1344]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 21 10:26:37 volumio volumio[1344]: info: ------------------------------ 9ms Feb 21 10:26:37 volumio volumio[1344]: info: ------------------------------ 4ms Feb 21 10:26:37 volumio volumio[1344]: info: sendMpdCommand play took 2 milliseconds Feb 21 10:26:38 volumio volumio[1344]: info: CoreCommandRouter::volumioPlay Feb 21 10:26:38 volumio volumio[1344]: info: CoreStateMachine::play index undefined Feb 21 10:26:38 volumio volumio[1344]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 10:26:38 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:38 volumio volumio[1344]: info: CoreStateMachine::startPlaybackTimer Feb 21 10:26:38 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:38 volumio volumio[1344]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 10:26:38 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:26:38 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 10:26:38 volumio volumio[1344]: verbose: ControllerMpd::clearAddPlayTracks cdda:///18 Feb 21 10:26:38 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 10:26:41 volumio volumio[1344]: info: Feb 21 10:26:41 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand stop took 3290 milliseconds Feb 21 10:26:41 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 10:26:41 volumio volumio[1344]: info: Feb 21 10:26:41 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:41 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand status took 2 milliseconds Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand clear took 3 milliseconds Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand add "cdda:///18" Feb 21 10:26:41 volumio volumio[1344]: error: updateQueue error: null Feb 21 10:26:41 volumio volumio[1344]: info: Feb 21 10:26:41 volumio volumio[1344]: ---------------------------- MPD announces system playlist update Feb 21 10:26:41 volumio volumio[1344]: info: Ignoring MPD Status Update Feb 21 10:26:41 volumio volumio[1344]: info: ------------------------------ 4ms Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand add "cdda:///18" took 3 milliseconds Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:41 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand play Feb 21 10:26:41 volumio volumio[1344]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 21 10:26:41 volumio volumio[1344]: info: ------------------------------ 11ms Feb 21 10:26:41 volumio volumio[1344]: info: ------------------------------ 4ms Feb 21 10:26:41 volumio volumio[1344]: info: sendMpdCommand play took 2 milliseconds Feb 21 10:26:44 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Feb 21 10:26:44 volumio volumio[1344]: info: MRS: Setting this device as single Feb 21 10:26:44 volumio volumio[1344]: info: Feb 21 10:26:44 volumio volumio[1344]: [1771669604151] ---------------------------- Setting Multiroom Single Feb 21 10:26:44 volumio volumio[1344]: info: MRS: disable multiroom output Feb 21 10:26:44 volumio volumio[1344]: info: MRS: Set multiroom target PCM to volumioDiscard Feb 21 10:26:44 volumio volumio[1344]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Feb 21 10:26:44 volumio volumio[1344]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 21 10:26:44 volumio volumio[1344]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Feb 21 10:26:45 volumio volumio[1344]: info: Feb 21 10:26:45 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:45 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:45 volumio volumio[1344]: info: Feb 21 10:26:45 volumio volumio[1344]: ---------------------------- MPD announces state update: player Feb 21 10:26:45 volumio volumio[1344]: info: sendMpdCommand status took 3 milliseconds Feb 21 10:26:45 volumio volumio[1344]: info: ControllerMpd::getState Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand status Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:45 volumio volumio[1344]: info: sendMpdCommand status took 2 milliseconds Feb 21 10:26:45 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::parseState Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:45 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18","artist":null,"album":null,"uri":"cdda:///18","trackType":"CD Audio"} Feb 21 10:26:45 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus stop Feb 21 10:26:45 volumio volumio[1344]: info: ------------------------------ 11ms Feb 21 10:26:45 volumio volumio[1344]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 21 10:26:45 volumio volumio[1344]: verbose: ControllerMpd::parseTrackInfo Feb 21 10:26:45 volumio volumio[1344]: info: ControllerMpd::pushState Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::servicePushState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18","artist":null,"album":null,"uri":"cdda:///18","trackType":"CD Audio"} Feb 21 10:26:45 volumio volumio[1344]: verbose: CURRENT POSITION 17 Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::syncState stateService play Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::syncState currentStatus play Feb 21 10:26:45 volumio volumio[1344]: info: Received an update from plugin. extracting info from payload Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: info: CoreStateMachine::pushState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::volumioPushState Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Pushing multiroomSync output update for this device Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Pushing multiroomSync output Feb 21 10:26:45 volumio volumio[1344]: info: CoreCommandRouter::volumioGetState Feb 21 10:26:45 volumio volumio[1344]: info: CorePlayQueue::getTrack 17 Feb 21 10:26:45 volumio volumio[1344]: info: ------------------------------ 72ms Feb 21 10:26:45 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:26:45 volumio volumio[1344]: info: PeppySpectrum ---peppyspectrum status play Feb 21 10:26:45 volumio sudo[3882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:26:45 volumio sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:45 volumio sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 21 10:26:45 volumio sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:45 volumio systemd[1]: Started peppyspectrum Daemon. Feb 21 10:26:45 volumio sudo[3882]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:45 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:26:45 volumio sudo[3885]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:45 volumio volumio[1344]: info: peppyspectrum Daemon Started Feb 21 10:26:45 volumio volumio[1344]: info: MRS: STOPPING volumioStreaming Feb 21 10:26:45 volumio sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 21 10:26:45 volumio sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio systemd[1]: Stopping VolumioStreamingService... Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 21 10:26:45 volumio systemd[1]: volumioStreaming.service: Succeeded. Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio systemd[1]: Stopped VolumioStreamingService. Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio volumio[3886]: libpng warning: iCCP: known incorrect sRGB profile Feb 21 10:26:45 volumio sudo[3890]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:45 volumio volumio[1344]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 10:26:45 volumio sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 21 10:26:45 volumio sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 10:26:45 volumio volumio[1344]: info: MRS: volumioStreaming STOPPED Feb 21 10:26:45 volumio sudo[3897]: pam_unix(sudo:session): session closed for user root Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Removed streaming files Feb 21 10:26:45 volumio volumio[1344]: info: MRS: Stopping sonos device: uuid:RINCON_000E5852128801400 Feb 21 10:26:47 volumio volumio[1344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 10:26:47 volumio volumio[1344]: TypeError: Cannot read property 'deviceDescription' of null Feb 21 10:26:47 volumio volumio[1344]: at multiroom.startSonosDevice (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:30673) Feb 21 10:26:47 volumio volumio[1344]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:4783) Feb 21 10:26:47 volumio volumio[1344]: at listOnTimeout (internal/timers.js:557:17) Feb 21 10:26:47 volumio volumio[1344]: at processTimers (internal/timers.js:500:7) Feb 21 10:26:47 volumio volumio[1344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 10:26:47 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-21 10:25 Feb 21 10:26:47 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="58e9bc1d7073d86e583c7b0145c7ac1a2c50cfc4" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 16 Jan 2026 06:00:45 PM CET" VOLUMIO_VERSION="3.896" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="99cd4beec494e07e64c515cb446cd2c2"