-- Logs begin at Mon 2025-02-24 14:13:37 CET, end at Mon 2025-02-24 14:33:31 CET. -- Feb 24 14:32:00 volumio-mllse mpd[6200]: Feb 24 14:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 14:32:00 volumio-mllse systemd[1]: Started Music Player Daemon. Feb 24 14:32:00 volumio-mllse sudo[6190]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:00 volumio-mllse systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Feb 24 14:32:00 volumio-mllse volumio[1252]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 14:32:00 volumio-mllse volumio[1252]: error: updateQueue error: null Feb 24 14:32:00 volumio-mllse volumio[6203]: cset: --> shielding system active with Feb 24 14:32:00 volumio-mllse volumio[6203]: cset: "system" cpuset of CPUSPEC(1) with 212 tasks running Feb 24 14:32:00 volumio-mllse volumio[6203]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 24 14:32:00 volumio-mllse systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Feb 24 14:32:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 14:32:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 14:32:03 volumio-mllse volumio[1252]: info: Discovery: Getting this device information Feb 24 14:32:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetState Feb 24 14:32:03 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPlay Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreStateMachine::play index undefined Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreStateMachine::startPlaybackTimer Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:18 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 14:32:18 volumio-mllse volumio[1252]: verbose: MPD COMMAND [object Object] Feb 24 14:32:18 volumio-mllse volumio[1252]: verbose: MPD COMMAND [object Object] Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPlay Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreStateMachine::play index undefined Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreStateMachine::startPlaybackTimer Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:19 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 14:32:19 volumio-mllse volumio[1252]: verbose: MPD COMMAND [object Object] Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioSeek Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreStateMachine::seek Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:20 volumio-mllse volumio[1252]: info: TRACKBLOCK {"service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"eighties","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music125/v4/68/44/d1/6844d1f7-c8a9-aac0-16bc-f3a30d3ff3ad/source/600x600bb.jpg","uri":"http://streams.80s80s.de/web/mp3-192/volumio","name":"Bronski Beat - Smalltown Boy","duration":"291","artist":"80s80s Real 80s Radio","album":"","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreStateMachine::startPlaybackTimer Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 14:32:20 volumio-mllse volumio[1252]: info: [1740403940577] [80s80s] seek to 194000 Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:20 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:20 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:20 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:20 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:20 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:20 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:20 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:21 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetQueue Feb 24 14:32:21 volumio-mllse volumio[1252]: info: CoreStateMachine::getQueue Feb 24 14:32:21 volumio-mllse volumio[1252]: info: CorePlayQueue::getQueue Feb 24 14:32:23 volumio-mllse volumio[1252]: info: MRS: STARTING volumioStreaming Feb 24 14:32:23 volumio-mllse sudo[6262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 14:32:23 volumio-mllse sudo[6262]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:23 volumio-mllse sudo[6262]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:23 volumio-mllse sudo[6265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 24 14:32:23 volumio-mllse sudo[6265]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:23 volumio-mllse systemd[1]: Stopping VolumioStreamingService... Feb 24 14:32:23 volumio-mllse systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 24 14:32:23 volumio-mllse volumio[1252]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 14:32:23 volumio-mllse systemd[1]: volumioStreaming.service: Succeeded. Feb 24 14:32:23 volumio-mllse systemd[1]: Stopped VolumioStreamingService. Feb 24 14:32:23 volumio-mllse volumio[1252]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 14:32:23 volumio-mllse systemd[1]: Started VolumioStreamingService. Feb 24 14:32:23 volumio-mllse sudo[6265]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:23 volumio-mllse volumio[1252]: info: MRS: volumioStreaming STARTED Feb 24 14:32:23 volumio-mllse sudo[6269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 14:32:23 volumio-mllse sudo[6269]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:23 volumio-mllse sudo[6269]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:24 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri Feb 24 14:32:24 volumio-mllse volumio[1252]: info: Preload queue cleared Feb 24 14:32:24 volumio-mllse volumio[1252]: info: Executing endpoint getSimilarAlbums Feb 24 14:32:24 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 14:32:25 volumio-mllse volumio[1252]: info: Executing endpoint metavolumio Feb 24 14:32:25 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 14:32:25 volumio-mllse volumio[1252]: info: Executing endpoint metavolumio Feb 24 14:32:25 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 14:32:25 volumio-mllse volumio[1252]: error: Failed request for metavolumio API Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Preload queue cleared Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::ClearQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::updateTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::stPlaybackTimer Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::serviceStop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::serviceStop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: ControllerMpd::stop Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::clearPlayQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::saveQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::addQueueItems Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::addQueueItems Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Preload queue cleared Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track0 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:26 volumio-mllse volumio[1252]: info: sendMpdCommand stop took 71 milliseconds Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::servicePushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music125/v4/68/44/d1/6844d1f7-c8a9-aac0-16bc-f3a30d3ff3ad/source/600x600bb.jpg","name":"Bronski Beat - Smalltown Boy","title":"Smalltown Boy","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"291","seek":115184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: CURRENT POSITION 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState stateService stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState currentStatus stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:26 volumio-mllse volumio[1252]: info: No code Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:26 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:26 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::saveQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::updateTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPlay Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::play index 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::addQueueItems Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::addQueueItems Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Preload queue cleared Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track1 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track1 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track2 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track2 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track3 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track3 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track4 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track4 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track5 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track5 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track6 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track6 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track7 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track7 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track8 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track8 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Adding Item to queue: cdaudio/track9 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Exploding uri cdaudio/track9 in service cd_controller Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::play index undefined Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::startPlaybackTimer Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 14:32:26 volumio-mllse volumio[1252]: info: sendMpdCommand stop took 4 milliseconds Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Feb 24 14:32:26 volumio-mllse volumio[1252]: ---------------------------- MPD announces system playlist update Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Ignoring MPD Status Update Feb 24 14:32:26 volumio-mllse volumio[1252]: info: sendMpdCommand clear took 2 milliseconds Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" Feb 24 14:32:26 volumio-mllse volumio[1252]: error: updateQueue error: null Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Feb 24 14:32:26 volumio-mllse volumio[1252]: ---------------------------- MPD announces system playlist update Feb 24 14:32:26 volumio-mllse volumio[1252]: info: Ignoring MPD Status Update Feb 24 14:32:26 volumio-mllse volumio[1252]: info: ------------------------------ 5ms Feb 24 14:32:26 volumio-mllse volumio[1252]: info: sendMpdCommand add "cdda:///1" took 2 milliseconds Feb 24 14:32:26 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand play Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::saveQueue Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CoreStateMachine::updateTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrackBlock Feb 24 14:32:26 volumio-mllse volumio[1252]: info: ------------------------------ 16ms Feb 24 14:32:26 volumio-mllse volumio[1252]: info: sendMpdCommand play took 12 milliseconds Feb 24 14:32:28 volumio-mllse kernel: usb 1-3: USB disconnect, device number 7 Feb 24 14:32:28 volumio-mllse volumio[1252]: info: Feb 24 14:32:28 volumio-mllse volumio[1252]: ---------------------------- MPD announces state update: player Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ControllerMpd::getState Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Feb 24 14:32:28 volumio-mllse volumio[1252]: info: Feb 24 14:32:28 volumio-mllse volumio[1252]: ---------------------------- MPD announces state update: player Feb 24 14:32:28 volumio-mllse volumio[1252]: info: sendMpdCommand status took 4 milliseconds Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ControllerMpd::getState Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseState Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 14:32:28 volumio-mllse volumio[1252]: info: sendMpdCommand status took 1 milliseconds Feb 24 14:32:28 volumio-mllse volumio[1252]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseState Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseTrackInfo Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ControllerMpd::pushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreCommandRouter::servicePushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: CURRENT POSITION 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState stateService play Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState currentStatus stop Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ------------------------------ 7ms Feb 24 14:32:28 volumio-mllse volumio[1252]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseTrackInfo Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ControllerMpd::pushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreCommandRouter::servicePushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Feb 24 14:32:28 volumio-mllse volumio[1252]: verbose: CURRENT POSITION 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState stateService play Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState currentStatus play Feb 24 14:32:28 volumio-mllse volumio[1252]: info: Received an update from plugin. extracting info from payload Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:28 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:28 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:28 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:28 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:28 volumio-mllse volumio[1252]: info: ------------------------------ 27ms Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:28 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: new high-speed USB device number 8 using xhci_hcd Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: New USB device found, idVendor=13fd, idProduct=0840, bcdDevice= 1.14 Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: Product: External Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: Manufacturer: Generic Feb 24 14:32:30 volumio-mllse kernel: usb 1-3: SerialNumber: 4B5A4C413947463234333720 Feb 24 14:32:30 volumio-mllse kernel: usb-storage 1-3:1.0: USB Mass Storage device detected Feb 24 14:32:30 volumio-mllse kernel: scsi host3: usb-storage 1-3:1.0 Feb 24 14:32:30 volumio-mllse volumio[1252]: info: Executing endpoint metavolumio Feb 24 14:32:30 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 14:32:30 volumio-mllse volumio[1252]: info: Executing endpoint metavolumio Feb 24 14:32:30 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 14:32:30 volumio-mllse volumio[1252]: info: Executing endpoint metavolumio Feb 24 14:32:30 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 14:32:31 volumio-mllse kernel: scsi 3:0:0:0: CD-ROM HL-DT-ST DVDRAM GT32N 1.00 PQ: 0 ANSI: 0 Feb 24 14:32:31 volumio-mllse kernel: sr 3:0:0:0: Power-on or device reset occurred Feb 24 14:32:31 volumio-mllse kernel: sr 3:0:0:0: [sr1] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray Feb 24 14:32:31 volumio-mllse kernel: sr 3:0:0:0: Attached scsi CD-ROM sr1 Feb 24 14:32:31 volumio-mllse kernel: sr 3:0:0:0: Attached scsi generic sg0 type 5 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioStop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::stop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::stPlaybackTimer Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::updateTrackBlock Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrackBlock Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::serviceStop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::serviceStop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: ControllerMpd::stop Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioResetState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: Clearing queue from CD entries Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:32 volumio-mllse volumio[1252]: Cannot find translation for source 80s80s Radio Feb 24 14:32:32 volumio-mllse volumio[1252]: Cannot find translation for source Mother Earth Radio Feb 24 14:32:32 volumio-mllse volumio[1252]: Cannot find translation for source Spotify Feb 24 14:32:32 volumio-mllse volumio[1252]: Cannot find translation for source USB Audio and HID Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::resetVolumioState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::getcurrentVolume Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:32 volumio-mllse volumio[1252]: info: Feb 24 14:32:32 volumio-mllse volumio[1252]: ---------------------------- MPD announces state update: player Feb 24 14:32:32 volumio-mllse volumio[1252]: info: sendMpdCommand stop took 101 milliseconds Feb 24 14:32:32 volumio-mllse volumio[1252]: info: ControllerMpd::getState Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Feb 24 14:32:32 volumio-mllse volumio[1252]: info: sendMpdCommand status took 0 milliseconds Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseState Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 14:32:32 volumio-mllse volumio[1252]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:32:32 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:32:32 volumio-mllse volumio[1252]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: ControllerMpd::parseTrackInfo Feb 24 14:32:32 volumio-mllse volumio[1252]: info: ControllerMpd::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::servicePushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: 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":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Feb 24 14:32:32 volumio-mllse volumio[1252]: verbose: CURRENT POSITION 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState stateService stop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::syncState currentStatus stop Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: No code Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: ------------------------------ 58ms Feb 24 14:32:32 volumio-mllse volumio[1252]: info: VolumeController:: Volume=undefined Mute =false Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::updateTrackBlock Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrackBlock Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 65 Feb 24 14:32:32 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:32:32 volumio-mllse volumio[1252]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:32:32 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:32:32 volumio-mllse volumio[1252]: info: VolumeController::SetAlsaVolume0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: info: VolumeController::SetAlsaVolume0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:32 volumio-mllse volumio[1252]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:32:32 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:32:32 volumio-mllse volumio[1252]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:32:32 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:32:32 volumio-mllse volumio[1252]: info: VolumeController:: Volume=undefined Mute =false Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:32 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:32 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:33 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 14:32:33 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 14:32:33 volumio-mllse volumio[1252]: info: Discovery: Getting this device information Feb 24 14:32:33 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetState Feb 24 14:32:33 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:33 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 14:32:35 volumio-mllse volumio[1252]: info: MRS: STARTING volumioStreaming Feb 24 14:32:35 volumio-mllse sudo[6332]: 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 Feb 24 14:32:35 volumio-mllse sudo[6332]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:35 volumio-mllse sudo[6332]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:35 volumio-mllse sudo[6335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 24 14:32:35 volumio-mllse sudo[6335]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:35 volumio-mllse systemd[1]: Stopping VolumioStreamingService... Feb 24 14:32:35 volumio-mllse systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 24 14:32:35 volumio-mllse volumio[1252]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 14:32:35 volumio-mllse systemd[1]: volumioStreaming.service: Succeeded. Feb 24 14:32:35 volumio-mllse systemd[1]: Stopped VolumioStreamingService. Feb 24 14:32:35 volumio-mllse volumio[1252]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 14:32:35 volumio-mllse systemd[1]: Started VolumioStreamingService. Feb 24 14:32:35 volumio-mllse sudo[6335]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:35 volumio-mllse volumio[1252]: info: MRS: volumioStreaming STARTED Feb 24 14:32:35 volumio-mllse sudo[6338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 14:32:35 volumio-mllse sudo[6338]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:35 volumio-mllse sudo[6338]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:38 volumio-mllse sudo[6368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr0 Feb 24 14:32:38 volumio-mllse sudo[6368]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:38 volumio-mllse sudo[6368]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:38 volumio-mllse volumio[1252]: /bin/chmod: cannot access '/dev/sr0': No such file or directory Feb 24 14:32:38 volumio-mllse sudo[6371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr1 Feb 24 14:32:38 volumio-mllse sudo[6371]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:32:38 volumio-mllse sudo[6371]: pam_unix(sudo:session): session closed for user root Feb 24 14:32:38 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 14:32:38 volumio-mllse volumio[1252]: info: [1740403958248] CoreMusicLibrary::Adding element Audio CD Feb 24 14:32:38 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:38 volumio-mllse volumio[1252]: Cannot find translation for source 80s80s Radio Feb 24 14:32:38 volumio-mllse volumio[1252]: Cannot find translation for source Mother Earth Radio Feb 24 14:32:38 volumio-mllse volumio[1252]: Cannot find translation for source Spotify Feb 24 14:32:38 volumio-mllse volumio[1252]: Cannot find translation for source USB Audio and HID Feb 24 14:32:38 volumio-mllse volumio[1252]: Cannot find translation for source Audio CD Feb 24 14:32:38 volumio-mllse volumio[1252]: /usr/bin/eject: unable to find or open device for: `cdrom' Feb 24 14:32:38 volumio-mllse volumio[1252]: error: Cannot set CD Speed Feb 24 14:32:39 volumio-mllse volumio[1252]: info: CDDB Entry Already exists Feb 24 14:32:39 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 14:32:39 volumio-mllse volumio[1252]: info: [1740403959987] CoreMusicLibrary::Adding element Audio CD Feb 24 14:32:39 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 14:32:39 volumio-mllse volumio[1252]: Cannot find translation for source 80s80s Radio Feb 24 14:32:39 volumio-mllse volumio[1252]: Cannot find translation for source Mother Earth Radio Feb 24 14:32:39 volumio-mllse volumio[1252]: Cannot find translation for source Spotify Feb 24 14:32:39 volumio-mllse volumio[1252]: Cannot find translation for source USB Audio and HID Feb 24 14:32:39 volumio-mllse volumio[1252]: Cannot find translation for source Audio CD Feb 24 14:32:42 volumio-mllse volumiologrotate[832]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 24 14:32:42 volumio-mllse volumiologrotate[832]: ls: cannot access 'MLLSE': No such file or directory Feb 24 14:32:58 volumio-mllse volumio[1252]: info: VolumeController::SetAlsaVolume+ Feb 24 14:32:58 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:32:58 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:32:58 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:32:58 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:32:58 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:32:58 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:32:58 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Feb 24 14:32:58 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 64 Feb 24 14:32:58 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 1 Feb 24 14:32:58 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 14:32:58 volumio-mllse volumio[1252]: info: Setting Spotify Volume from Volumio: 1 Feb 24 14:32:58 volumio-mllse volumio[1252]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:32:58 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:33:00 volumio-mllse volumio[1252]: SPOTIFY: SETTING SPOTIFY VOLUME 1 Feb 24 14:33:00 volumio-mllse volumio[1252]: info: Sending Spotify command with payload to local API: /player/volume Feb 24 14:33:00 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:00+01:00" level=debug msg="update volume to 655/65535" Feb 24 14:33:00 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:00+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 24 14:33:00 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:00+01:00" level=trace msg="emitting websocket event: volume" Feb 24 14:33:00 volumio-mllse volumio[1252]: SPOTIFY: received: {"type":"volume","data":{"value":1,"max":100}} Feb 24 14:33:00 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED SPOTIFY VOLUME 1 Feb 24 14:33:02 volumio-mllse volumio[1252]: info: VolumeController::SetAlsaVolume+ Feb 24 14:33:02 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:33:02 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:33:02 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:33:02 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:33:02 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:33:02 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:33:02 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 2 Feb 24 14:33:02 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 1 Feb 24 14:33:02 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 2 Feb 24 14:33:02 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 14:33:02 volumio-mllse volumio[1252]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:33:02 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:33:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 14:33:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 14:33:03 volumio-mllse volumio[1252]: info: Discovery: Getting this device information Feb 24 14:33:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioGetState Feb 24 14:33:03 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:33:03 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 14:33:08 volumio-mllse volumio[1252]: info: VolumeController::SetAlsaVolume+ Feb 24 14:33:08 volumio-mllse volumio[1252]: info: CoreStateMachine::pushState Feb 24 14:33:08 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:33:08 volumio-mllse volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:33:08 volumio-mllse volumio[1252]: info: CoreCommandRouter::volumioPushState Feb 24 14:33:08 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Feb 24 14:33:08 volumio-mllse volumio[1252]: info: MRS: Pushing multiroomSync output Feb 24 14:33:08 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Feb 24 14:33:08 volumio-mllse volumio[1252]: SPOTIFY: SPOTIFY VOLUME 1 Feb 24 14:33:08 volumio-mllse volumio[1252]: SPOTIFY: VOLUMIO VOLUME 3 Feb 24 14:33:08 volumio-mllse volumio[1252]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 14:33:08 volumio-mllse volumio[1252]: info: Setting Spotify Volume from Volumio: 3 Feb 24 14:33:08 volumio-mllse volumio[1252]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 24 14:33:08 volumio-mllse volumio[1252]: xcb_connection_has_error() returned true Feb 24 14:33:09 volumio-mllse volumio[1252]: SPOTIFY: SETTING SPOTIFY VOLUME 3 Feb 24 14:33:09 volumio-mllse volumio[1252]: info: Sending Spotify command with payload to local API: /player/volume Feb 24 14:33:09 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:09+01:00" level=debug msg="update volume to 1966/65535" Feb 24 14:33:09 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:09+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 24 14:33:09 volumio-mllse go-librespot[1754]: time="2025-02-24T14:33:09+01:00" level=trace msg="emitting websocket event: volume" Feb 24 14:33:09 volumio-mllse volumio[1252]: SPOTIFY: received: {"type":"volume","data":{"value":3,"max":100}} Feb 24 14:33:09 volumio-mllse volumio[1252]: SPOTIFY: RECEIVED SPOTIFY VOLUME 3 Feb 24 14:33:31 volumio-mllse volumio[1252]: info: [1740404011353] [80s80s] Pushing the next song state Erasure - Oh L'amour and getting next track. Feb 24 14:33:31 volumio-mllse volumio[1252]: info: CorePlayQueue::getTrack 0 Feb 24 14:33:31 volumio-mllse volumio[1252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:33:31 volumio-mllse volumio[1252]: TypeError: Cannot set property 'name' of undefined Feb 24 14:33:31 volumio-mllse volumio[1252]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Feb 24 14:33:31 volumio-mllse volumio[1252]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Feb 24 14:33:31 volumio-mllse volumio[1252]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Feb 24 14:33:31 volumio-mllse volumio[1252]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Feb 24 14:33:31 volumio-mllse volumio[1252]: at processImmediate (internal/timers.js:464:21) Feb 24 14:33:31 volumio-mllse volumio[1252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:33:31 volumio-mllse sudo[6871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 14:32 Feb 24 14:33:31 volumio-mllse sudo[6871]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"