Sep 17 14:35:00 volumio volumio[1292]: info: Preload queue cleared Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::ClearQueue Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::serviceStop Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::serviceStop Sep 17 14:35:00 volumio volumio[1292]: info: Airplay Stop Sep 17 14:35:00 volumio volumio[1292]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::clearPlayQueue Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::addQueueItems Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::addQueueItems Sep 17 14:35:00 volumio volumio[1292]: info: Preload queue cleared Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/01 - Queendom.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/01 - Queendom.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/02 - Forgotten Love.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/02 - Forgotten Love.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/03 - Gentle Earthquakes.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/03 - Gentle Earthquakes.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::updateTrackBlock Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrackBlock Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioGetState Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPlay Sep 17 14:35:00 volumio volumio[1292]: verbose: UNSET VOLATILE: Service: airplay_emulation Sep 17 14:35:00 volumio volumio[1292]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::play index 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::addQueueItems Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::addQueueItems Sep 17 14:35:00 volumio volumio[1292]: info: Preload queue cleared Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/05 - It Happened Quiet.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/05 - It Happened Quiet.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/06 - Churchyard.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/06 - Churchyard.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/07 - Soft Universe.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/07 - Soft Universe.flac Sep 17 14:35:00 volumio volumio[1292]: info: Adding Item to queue: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/08 - Infections of a Different Kind.flac Sep 17 14:35:00 volumio volumio[1292]: info: Using cached record of: music-library/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/08 - Infections of a Different Kind.flac Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::play index undefined Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::updateTrackBlock Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrackBlock Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::startPlaybackTimer Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::clearAddPlayTracks USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand stop took 4 milliseconds Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 14:35:00 volumio volumio[1292]: info: Sep 17 14:35:00 volumio volumio[1292]: ---------------------------- MPD announces system playlist update Sep 17 14:35:00 volumio volumio[1292]: info: Ignoring MPD Status Update Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand clear took 1 milliseconds Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand add "USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac" Sep 17 14:35:00 volumio volumio[1292]: error: updateQueue error: null Sep 17 14:35:00 volumio volumio[1292]: info: Sep 17 14:35:00 volumio volumio[1292]: ---------------------------- MPD announces system playlist update Sep 17 14:35:00 volumio volumio[1292]: info: Ignoring MPD Status Update Sep 17 14:35:00 volumio volumio[1292]: info: ------------------------------ 1ms Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand add "USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac" took 1 milliseconds Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand play Sep 17 14:35:00 volumio volumio[1292]: info: ------------------------------ 2ms Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand play took 3 milliseconds Sep 17 14:35:00 volumio sudo[20827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1843 Sep 17 14:35:00 volumio sudo[20827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 14:35:00 volumio sudo[20827]: pam_unix(sudo:session): session closed for user root Sep 17 14:35:00 volumio volumio[1292]: info: Sep 17 14:35:00 volumio volumio[1292]: ---------------------------- MPD announces state update: player Sep 17 14:35:00 volumio shairport-sync[18025]: {"time":1758110890651,"response":"airplayInactive Success"} Sep 17 14:35:00 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Sep 17 14:35:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Sep 17 14:35:00 volumio volumio[1292]: info: ControllerMpd::getState Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Sep 17 14:35:00 volumio volumio[1292]: info: Sep 17 14:35:00 volumio volumio[1292]: ---------------------------- MPD announces state update: player Sep 17 14:35:00 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand status took 3 milliseconds Sep 17 14:35:00 volumio volumio[1292]: info: ControllerMpd::getState Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::parseState Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 14:35:00 volumio volumio[1292]: info: Shairport-Sync paused with USR2 Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand status took 3 milliseconds Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::parseState Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::parseTrackInfo Sep 17 14:35:00 volumio volumio[1292]: info: ControllerMpd::pushState Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All Is Soft Inside","artist":"AURORA","album":"Infections of a Different Kind – Step 1","uri":"USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac","trackType":"flac"} Sep 17 14:35:00 volumio volumio[1292]: verbose: CURRENT POSITION 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::syncState stateService play Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::syncState currentStatus stop Sep 17 14:35:00 volumio volumio[1292]: info: ------------------------------ 15ms Sep 17 14:35:00 volumio volumio[1292]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 17 14:35:00 volumio volumio[1292]: verbose: ControllerMpd::parseTrackInfo Sep 17 14:35:00 volumio volumio[1292]: info: ControllerMpd::pushState Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"All Is Soft Inside","artist":"AURORA","album":"Infections of a Different Kind – Step 1","uri":"USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac","trackType":"flac"} Sep 17 14:35:00 volumio volumio[1292]: verbose: CURRENT POSITION 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::syncState stateService play Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::syncState currentStatus play Sep 17 14:35:00 volumio volumio[1292]: info: Received an update from plugin. extracting info from payload Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:00 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:00 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:00 volumio sudo[20828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1843 Sep 17 14:35:00 volumio systemd[1]: shairport-sync.service: Consumed 49.953s CPU time. Sep 17 14:35:00 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:00 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:00 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:00 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:00 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:00 volumio sudo[20828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 14:35:00 volumio sudo[20828]: pam_unix(sudo:session): session closed for user root Sep 17 14:35:00 volumio volumio[1292]: info: ------------------------------ 25ms Sep 17 14:35:00 volumio volumio[1292]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Sep 17 14:35:00 volumio volumio[1292]: /bin/kill: (1843): No such process Sep 17 14:35:00 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 17 14:35:00 volumio dbus-daemon[714]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.233' (uid=0 pid=20832 comm="timedatectl show --property=NTPSynchronized --valu") Sep 17 14:35:00 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 17 14:35:00 volumio dbus-daemon[714]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 17 14:35:00 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 17 14:35:00 volumio setdatetime-helper.sh[20831]: Time is already synchronized. Sep 17 14:35:00 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 17 14:35:00 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 17 14:35:01 volumio volumio[1292]: info: Restarting Shairport-Sync after stop Sep 17 14:35:01 volumio sudo[20836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 14:35:01 volumio sudo[20836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 14:35:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 17 14:35:01 volumio sudo[20836]: pam_unix(sudo:session): session closed for user root Sep 17 14:35:01 volumio volumio[1292]: info: Shairport-Sync retarted Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::volumioSeek Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::seek Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: info: TRACKBLOCK {"uri":"mnt/USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac","service":"mpd","name":"All Is Soft Inside","artist":"AURORA","album":"Infections of a Different Kind – Step 1","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=528&web=AURORA/Infections%20of%20a%20Different%20Kind%20%E2%80%93%20Step%201/extralarge&path=%2FUSB%2F2TB_SSD_T7%2F%5B2018%5D%20Infections%20Of%20A%20Different%20Kind&metadata=false","duration":309,"samplerate":"48 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::startPlaybackTimer Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: info: ControllerMpd::seek Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:05 volumio volumio[1292]: error: null Sep 17 14:35:05 volumio volumio[1292]: info: Sep 17 14:35:05 volumio volumio[1292]: ---------------------------- MPD announces state update: player Sep 17 14:35:05 volumio volumio[1292]: info: ControllerMpd::getState Sep 17 14:35:05 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Sep 17 14:35:05 volumio volumio[1292]: info: sendMpdCommand status took 1 milliseconds Sep 17 14:35:05 volumio volumio[1292]: verbose: ControllerMpd::parseState Sep 17 14:35:05 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 14:35:05 volumio volumio[1292]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 17 14:35:05 volumio volumio[1292]: verbose: ControllerMpd::parseTrackInfo Sep 17 14:35:05 volumio volumio[1292]: info: ControllerMpd::pushState Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":309,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"All Is Soft Inside","artist":"AURORA","album":"Infections of a Different Kind – Step 1","uri":"USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac","trackType":"flac"} Sep 17 14:35:05 volumio volumio[1292]: verbose: CURRENT POSITION 3 Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::syncState stateService play Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::syncState currentStatus play Sep 17 14:35:05 volumio volumio[1292]: info: Received an update from plugin. extracting info from payload Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:05 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:05 volumio volumio[1292]: info: ------------------------------ 26ms Sep 17 14:35:23 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 17 14:35:23 volumio volumio[1292]: info: browseTIDALUri took 165 milliseconds Sep 17 14:35:23 volumio volumio[1292]: info: Preload queue cleared Sep 17 14:35:23 volumio volumio[1292]: info: Preload queue cleared Sep 17 14:35:27 volumio volumio[1292]: Searching plugin music_service/tidal Sep 17 14:35:27 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 17 14:35:28 volumio volumio[1292]: info: searchTIDALUri took 274 milliseconds Sep 17 14:35:28 volumio volumio[1292]: info: search took 275 milliseconds Sep 17 14:35:28 volumio volumio[1292]: info: All search sources collected, pushing search results Sep 17 14:35:30 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 17 14:35:55 volumio shairport-sync[20838]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Sep 17 14:35:55 volumio shairport-sync[20838]: warning: alsa: error -16 ("Device or resource busy") opening alsa device "volumio". Sep 17 14:35:55 volumio volumio[1292]: info: Airplay started streaming, receiving metadatas Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stPlaybackTimer Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::updateTrackBlock Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrackBlock Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::serviceStop Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::serviceStop Sep 17 14:35:55 volumio volumio[1292]: info: ControllerMpd::stop Sep 17 14:35:55 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: Airplay started streaming, receiving metadatas Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: Airplay started streaming, receiving metadatas Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: Airplay started streaming, receiving metadatas Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: Sep 17 14:35:55 volumio volumio[1292]: ---------------------------- MPD announces state update: player Sep 17 14:35:55 volumio volumio[1292]: info: sendMpdCommand stop took 12 milliseconds Sep 17 14:35:55 volumio volumio[1292]: info: ControllerMpd::getState Sep 17 14:35:55 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Sep 17 14:35:55 volumio volumio[1292]: info: sendMpdCommand status took 1 milliseconds Sep 17 14:35:55 volumio volumio[1292]: verbose: ControllerMpd::parseState Sep 17 14:35:55 volumio volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 14:35:55 volumio volumio[1292]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 17 14:35:55 volumio volumio[1292]: verbose: ControllerMpd::parseTrackInfo Sep 17 14:35:55 volumio volumio[1292]: info: ControllerMpd::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: 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":"All Is Soft Inside","artist":"AURORA","album":"Infections of a Different Kind – Step 1","uri":"USB/2TB_SSD_T7/[2018] Infections Of A Different Kind/04 - All Is Soft Inside.flac","trackType":"flac"} Sep 17 14:35:55 volumio volumio[1292]: verbose: CURRENT POSITION 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::syncState stateService stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::syncState currentStatus stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:55 volumio volumio[1292]: info: No code Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:55 volumio shairport-sync[20944]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 14:35:55 volumio shairport-sync[20944]: Dload Upload Total Spent Left Speed Sep 17 14:35:55 volumio volumio[1292]: info: ------------------------------ 16ms Sep 17 14:35:55 volumio shairport-sync[20944]: [79B blob data] Sep 17 14:35:55 volumio shairport-sync[20945]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 14:35:55 volumio shairport-sync[20945]: Dload Upload Total Spent Left Speed Sep 17 14:35:55 volumio volumio[1292]: info: Sep 17 14:35:55 volumio volumio[1292]: ---------------------------- Client requests Start Airplay PlaybackRoutine Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Sep 17 14:35:55 volumio volumio[1292]: info: Airplay playback start Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 14:35:55 volumio shairport-sync[20945]: [79B blob data] Sep 17 14:35:55 volumio shairport-sync[20944]: [79B blob data] Sep 17 14:35:55 volumio volumio[1292]: info: Sep 17 14:35:55 volumio volumio[1292]: ---------------------------- Client requests AirplayActive Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Sep 17 14:35:55 volumio shairport-sync[20944]: {"time":1758112555714,"response":"startAirplayPlayback Success"} Sep 17 14:35:55 volumio shairport-sync[20945]: [79B blob data] Sep 17 14:35:55 volumio volumio[1292]: info: Updating volume from AirPlay: -18; 40% Sep 17 14:35:55 volumio volumio[1292]: info: VolumeController::SetAlsaVolume40 Sep 17 14:35:55 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:55 volumio volumio[1292]: info: CorePlayQueue::getTrack 3 Sep 17 14:35:55 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:55 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:56 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:56 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:35:57 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:35:57 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:35:57 volumio shairport-sync[20945]: {"time":1758112555716,"response":"airplayActive Success"} Sep 17 14:35:57 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=11/SEGV Sep 17 14:35:57 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Sep 17 14:35:57 volumio systemd[1]: shairport-sync.service: Consumed 1.840s CPU time. Sep 17 14:36:04 volumio volumio[1292]: info: CoreCommandRouter::volumioGetState Sep 17 14:36:04 volumio volumio[1292]: info: CoreCommandRouter::volumioGetState Sep 17 14:36:04 volumio volumio[1292]: info: Currently active: airplay_emulation Sep 17 14:36:04 volumio volumio[1292]: info: Stopping currently active service Sep 17 14:36:04 volumio volumio[1292]: info: CoreCommandRouter::volumioStop Sep 17 14:36:04 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:36:04 volumio volumio[1292]: info: CoreStateMachine::serviceStop Sep 17 14:36:04 volumio volumio[1292]: info: CoreCommandRouter::serviceStop Sep 17 14:36:04 volumio volumio[1292]: info: Airplay Stop Sep 17 14:36:04 volumio volumio[1292]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 17 14:36:04 volumio sudo[20970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 Sep 17 14:36:04 volumio sudo[20970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 14:36:04 volumio sudo[20970]: pam_unix(sudo:session): session closed for user root Sep 17 14:36:04 volumio volumio[1292]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Sep 17 14:36:04 volumio volumio[1292]: Usage: Sep 17 14:36:04 volumio volumio[1292]: kill [options] [...] Sep 17 14:36:04 volumio volumio[1292]: Options: Sep 17 14:36:04 volumio volumio[1292]: [...] send signal to every listed Sep 17 14:36:04 volumio volumio[1292]: -, -s, --signal Sep 17 14:36:04 volumio volumio[1292]: specify the to be sent Sep 17 14:36:04 volumio volumio[1292]: -q, --queue integer value to be sent with the signal Sep 17 14:36:04 volumio volumio[1292]: -l, --list=[] list all signal names, or convert one to a name Sep 17 14:36:04 volumio volumio[1292]: -L, --table list all signal names in a nice table Sep 17 14:36:04 volumio volumio[1292]: -h, --help display this help and exit Sep 17 14:36:04 volumio volumio[1292]: -V, --version output version information and exit Sep 17 14:36:04 volumio volumio[1292]: For more details see kill(1). Sep 17 14:36:05 volumio vtcs[1870]: [2025-09-17 14:36:05.426] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Sep 17 14:36:05 volumio vtcs[1870]: [2025-09-17 14:36:05.427] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Sep 17 14:36:05 volumio volumio[1292]: info: Pushing metadata Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:05 volumio volumio[1292]: info: TidalConnect Active Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::volumioGetState Sep 17 14:36:05 volumio volumio[1292]: info: Currently active: tidalconnect Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:05 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:05 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio vtcs[1870]: [close:85] Entering Sep 17 14:36:06 volumio vtcs[1870]: [close:100] Exiting Sep 17 14:36:06 volumio volumio[1292]: info: Pushing metadata Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio vtcs[1870]: [close:85] Entering Sep 17 14:36:06 volumio vtcs[1870]: [close:100] Exiting Sep 17 14:36:06 volumio vtcs[1870]: [open:50] Opening with sample rate: 44100, type: 0 Sep 17 14:36:06 volumio vtcs[1870]: [start:106] Entering Sep 17 14:36:06 volumio vtcs[1870]: [start:113] Reopening PCM device... Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio vtcs[1870]: [start:115] PCM opened successfully. Sep 17 14:36:06 volumio vtcs[1870]: [start:120] Exiting Sep 17 14:36:06 volumio vtcs[1870]: [feedThread:223] Entering Sep 17 14:36:06 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio vtcs[1870]: [2025-09-17 14:36:06.555] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::servicePushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreStateMachine::pushState Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 14:36:06 volumio volumio[1292]: info: CoreCommandRouter::volumioPushState Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output update for this device Sep 17 14:36:06 volumio volumio[1292]: info: MRS: Pushing multiroomSync output Sep 17 14:36:14 volumio volumio[1292]: info: CoreCommandRouter::volumioGetState Sep 17 14:36:16 volumio volumio[1292]: info: Executing endpoint metavolumio Sep 17 14:36:16 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 14:36:16 volumio volumio[1292]: info: Executing endpoint metavolumio Sep 17 14:36:16 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 14:36:16 volumio volumio[1292]: info: Executing endpoint metavolumio Sep 17 14:36:16 volumio volumio[1292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 14:36:21 volumio volumio[1292]: info: CoreCommandRouter::volumioRemoveQueueItem Sep 17 14:36:21 volumio volumio[1292]: info: CoreStateMachine::removeQueueItem Sep 17 14:36:21 volumio volumio[1292]: info: CorePlayQueue::removeQueueItem 0 Sep 17 14:36:21 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:36:21 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:36:22 volumio volumio[1292]: info: CoreCommandRouter::volumioRemoveQueueItem Sep 17 14:36:22 volumio volumio[1292]: info: CoreStateMachine::removeQueueItem Sep 17 14:36:22 volumio volumio[1292]: info: CorePlayQueue::removeQueueItem 0 Sep 17 14:36:22 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:36:22 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:36:23 volumio volumio[1292]: info: CoreCommandRouter::volumioRemoveQueueItem Sep 17 14:36:23 volumio volumio[1292]: info: CoreStateMachine::removeQueueItem Sep 17 14:36:23 volumio volumio[1292]: info: CorePlayQueue::removeQueueItem 0 Sep 17 14:36:23 volumio volumio[1292]: info: CorePlayQueue::saveQueue Sep 17 14:36:23 volumio volumio[1292]: info: CoreCommandRouter::volumioPushQueue Sep 17 14:36:23 volumio volumio[1292]: info: CoreCommandRouter::volumioRemoveQueueItem Sep 17 14:36:23 volumio volumio[1292]: info: CoreStateMachine::removeQueueItem Sep 17 14:36:23 volumio volumio[1292]: info: CoreStateMachine::stop Sep 17 14:36:23 volumio volumio[1292]: info: CoreStateMachine::serviceStop Sep 17 14:36:23 volumio volumio[1292]: info: CoreCommandRouter::serviceStop Sep 17 14:36:23 volumio vtcs[1870]: [close:85] Entering Sep 17 14:36:23 volumio volumio[1292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 14:36:23 volumio volumio[1292]: TypeError: Cannot read properties of undefined (reading 'then') Sep 17 14:36:23 volumio volumio[1292]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1370:7) Sep 17 14:36:23 volumio volumio[1292]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Sep 17 14:36:23 volumio volumio[1292]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Sep 17 14:36:23 volumio volumio[1292]: at Socket.emit (node:events:514:28) Sep 17 14:36:23 volumio volumio[1292]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 17 14:36:23 volumio volumio[1292]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Sep 17 14:36:23 volumio volumio[1292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 14:36:23 volumio vtcs[1870]: [feedThread:276] Exiting Sep 17 14:36:23 volumio vtcs[1870]: [close:100] Exiting Sep 17 14:36:24 volumio sudo[21025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-17 14:35' Sep 17 14:36:24 volumio sudo[21025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"