-- Logs begin at Wed 2024-04-10 11:47:13 CEST, end at Thu 2024-04-11 11:48:45 CEST. --
Apr 11 11:47:02 forestcut volumio[712]: Searching plugin music_service/webradio
Apr 11 11:47:02 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: webradio , search
Apr 11 11:47:02 forestcut volumio[712]: info: Shoutcast fout
Apr 11 11:47:03 forestcut volumio[712]: info: All search sources collected, pushing search results
Apr 11 11:47:05 forestcut volumio[712]: info: Preload queue cleared
Apr 11 11:47:05 forestcut volumio[712]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 11 11:47:05 forestcut volumio[712]: info: CoreStateMachine::ClearQueue
Apr 11 11:47:05 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:47:05 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:05 forestcut volumio[712]: info: CorePlayQueue::clearPlayQueue
Apr 11 11:47:05 forestcut volumio[712]: info: CorePlayQueue::saveQueue
Apr 11 11:47:05 forestcut volumio[712]: info: CoreCommandRouter::volumioPushQueue
Apr 11 11:47:05 forestcut volumio[712]: info: CoreStateMachine::addQueueItems
Apr 11 11:47:05 forestcut volumio[712]: info: CorePlayQueue::addQueueItems
Apr 11 11:47:05 forestcut volumio[712]: info: Preload queue cleared
Apr 11 11:47:05 forestcut volumio[712]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309507
Apr 11 11:47:05 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Apr 11 11:47:06 forestcut volumio[712]: info: CoreCommandRouter::volumioPushQueue
Apr 11 11:47:06 forestcut volumio[712]: info: CorePlayQueue::saveQueue
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::updateTrackBlock
Apr 11 11:47:06 forestcut volumio[712]: info: CorePlayQueue::getTrackBlock
Apr 11 11:47:06 forestcut volumio[712]: info: CoreCommandRouter::volumioPlay
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::play index 0
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::play index undefined
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:06 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:06 forestcut volumio[712]: info: CoreStateMachine::startPlaybackTimer
Apr 11 11:47:06 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:06 forestcut volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 11 11:47:06 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 11 11:47:06 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 11 11:47:06 forestcut volumio[712]: info: [1712828826219] ControllerWebradio::clearAddPlayTrack
Apr 11 11:47:06 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand stop
Apr 11 11:47:06 forestcut volumio[712]: info: sendMpdCommand stop took 4 milliseconds
Apr 11 11:47:06 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand clear
Apr 11 11:47:06 forestcut volumio[712]: info:
Apr 11 11:47:06 forestcut volumio[712]: ---------------------------- MPD announces system playlist update
Apr 11 11:47:06 forestcut volumio[712]: info: Ignoring MPD Status Update
Apr 11 11:47:06 forestcut volumio[712]: info: sendMpdCommand clear took 1 milliseconds
Apr 11 11:47:06 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com"
Apr 11 11:47:06 forestcut volumio[712]: error: updateQueue error: null
Apr 11 11:47:06 forestcut volumio[712]: info: ------------------------------ 2ms
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com"
Apr 11 11:47:07 forestcut volumio[712]: info:
Apr 11 11:47:07 forestcut volumio[712]: ---------------------------- MPD announces system playlist update
Apr 11 11:47:07 forestcut volumio[712]: info: Ignoring MPD Status Update
Apr 11 11:47:07 forestcut volumio[712]: info: sendMpdCommand add "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com" took 1 milliseconds
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand play
Apr 11 11:47:07 forestcut volumio[712]: info: ------------------------------ 2ms
Apr 11 11:47:07 forestcut volumio[712]: info: sendMpdCommand play took 1 milliseconds
Apr 11 11:47:07 forestcut volumio[712]: info:
Apr 11 11:47:07 forestcut volumio[712]: ---------------------------- MPD announces state update: player
Apr 11 11:47:07 forestcut volumio[712]: info: ControllerMpd::getState
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand status
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 11 11:47:07 forestcut volumio[712]: info: sendMpdCommand status took 8 milliseconds
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::parseState
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 11 11:47:07 forestcut volumio[712]: info: sendMpdCommand clearerror took 2 milliseconds
Apr 11 11:47:07 forestcut volumio[712]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 11 11:47:07 forestcut volumio[712]: verbose: ControllerMpd::parseTrackInfo
Apr 11 11:47:07 forestcut volumio[712]: info: ControllerMpd::pushState
Apr 11 11:47:07 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:07 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 11 11:47:07 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:07 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:07 forestcut volumio[712]: 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":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
Apr 11 11:47:07 forestcut volumio[712]: verbose: CURRENT POSITION 0
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::syncState stateService stop
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::syncState currentStatus stop
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:07 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:07 forestcut volumio[712]: info: No code
Apr 11 11:47:07 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:07 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:07 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:07 forestcut volumio[712]: info: ------------------------------ 41ms
Apr 11 11:47:07 forestcut volumio[712]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gLuAMLfGWcffcIuEtoIApH1ZXG42.9053220a5d2af73eab53a23d72f04075.state.status'
Apr 11 11:47:07 forestcut volumio[712]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gLuAMLfGWcffcIuEtoIApH1ZXG42.9053220a5d2af73eab53a23d72f04075.state.status'
Apr 11 11:47:19 forestcut volumio[712]: info: Preload queue cleared
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::ClearQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::clearPlayQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::saveQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPushQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::addQueueItems
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::addQueueItems
Apr 11 11:47:19 forestcut volumio[712]: info: Preload queue cleared
Apr 11 11:47:19 forestcut volumio[712]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309507
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPushQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::saveQueue
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::updateTrackBlock
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::getTrackBlock
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPlay
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::play index 0
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::play index undefined
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::startPlaybackTimer
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 11 11:47:19 forestcut volumio[712]: info: [1712828839578] ControllerWebradio::clearAddPlayTrack
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand stop
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand stop took 3 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand clear
Apr 11 11:47:19 forestcut volumio[712]: info:
Apr 11 11:47:19 forestcut volumio[712]: ---------------------------- MPD announces system playlist update
Apr 11 11:47:19 forestcut volumio[712]: info: Ignoring MPD Status Update
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand clear took 1 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com"
Apr 11 11:47:19 forestcut volumio[712]: error: updateQueue error: null
Apr 11 11:47:19 forestcut volumio[712]: info: ------------------------------ 2ms
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com"
Apr 11 11:47:19 forestcut volumio[712]: info:
Apr 11 11:47:19 forestcut volumio[712]: ---------------------------- MPD announces system playlist update
Apr 11 11:47:19 forestcut volumio[712]: info: Ignoring MPD Status Update
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand add "http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com" took 1 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand play
Apr 11 11:47:19 forestcut volumio[712]: info: ------------------------------ 2ms
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand play took 1 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: info:
Apr 11 11:47:19 forestcut volumio[712]: ---------------------------- MPD announces state update: player
Apr 11 11:47:19 forestcut volumio[712]: info: ControllerMpd::getState
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand status
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand status took 5 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::parseState
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand clearerror took 1 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 11 11:47:19 forestcut volumio[712]: verbose: ControllerMpd::parseTrackInfo
Apr 11 11:47:19 forestcut volumio[712]: info: ControllerMpd::pushState
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:47:19 forestcut volumio[712]: 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":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/pop-xxl/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"}
Apr 11 11:47:19 forestcut volumio[712]: verbose: CURRENT POSITION 0
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::syncState stateService stop
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::syncState currentStatus stop
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:19 forestcut volumio[712]: info: No code
Apr 11 11:47:19 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:47:19 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:47:19 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:47:19 forestcut volumio[712]: info: ------------------------------ 39ms
Apr 11 11:47:19 forestcut volumio[712]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gLuAMLfGWcffcIuEtoIApH1ZXG42.9053220a5d2af73eab53a23d72f04075.state.status'
Apr 11 11:47:19 forestcut volumio[712]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gLuAMLfGWcffcIuEtoIApH1ZXG42.9053220a5d2af73eab53a23d72f04075.state.status'
Apr 11 11:48:19 forestcut volumio[712]: info: Airplay started streaming, receiving metadatas
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::volumioStop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: Airplay started streaming, receiving metadatas
Apr 11 11:48:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::volumioStop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: Airplay started streaming, receiving metadatas
Apr 11 11:48:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::volumioStop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: Airplay started streaming, receiving metadatas
Apr 11 11:48:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::volumioStop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut shairport-sync[1215]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 11 11:48:19 forestcut shairport-sync[1215]: Dload Upload Total Spent Left Speed
Apr 11 11:48:19 forestcut volumio[712]: info:
Apr 11 11:48:19 forestcut volumio[712]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Apr 11 11:48:19 forestcut volumio[712]: info: Airplay playback start
Apr 11 11:48:19 forestcut volumio[712]: info: CorePlayQueue::getTrack 0
Apr 11 11:48:19 forestcut volumio[712]: info: CoreCommandRouter::volumioStop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::stop
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 11 11:48:19 forestcut shairport-sync[1215]: [158B blob data]
Apr 11 11:48:19 forestcut shairport-sync[1215]: {"time":1712828899154,"response":"startAirplayPlayback Success"}ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536
Apr 11 11:48:19 forestcut shairport-sync[1215]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536
Apr 11 11:48:19 forestcut kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error!
Apr 11 11:48:19 forestcut shairport-sync[1215]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536
Apr 11 11:48:19 forestcut shairport-sync[1215]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:20 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:20 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::servicePushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreStateMachine::pushState
Apr 11 11:48:21 forestcut volumio[712]: info: CoreCommandRouter::volumioPushState
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output update for this device
Apr 11 11:48:21 forestcut volumio[712]: info: MRS: Pushing multiroomSync output
Apr 11 11:48:43 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 11 11:48:43 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 11 11:48:43 forestcut volumio[712]: info: Discovery: Getting this device information
Apr 11 11:48:43 forestcut volumio[712]: info: CoreCommandRouter::volumioGetState
Apr 11 11:48:43 forestcut volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 11 11:48:43 forestcut volumio[712]: verbose: New Socket.io Connection to 192.168.0.145 from 192.168.0.178 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6
Apr 11 11:48:45 forestcut volumio[712]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 11 11:48:45 forestcut volumio[712]: Error: connect ECONNREFUSED 151.101.10.79:80
Apr 11 11:48:45 forestcut volumio[712]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Apr 11 11:48:45 forestcut volumio[712]: errno: -111,
Apr 11 11:48:45 forestcut volumio[712]: code: 'ECONNREFUSED',
Apr 11 11:48:45 forestcut volumio[712]: syscall: 'connect',
Apr 11 11:48:45 forestcut volumio[712]: address: '151.101.10.79',
Apr 11 11:48:45 forestcut volumio[712]: port: 80
Apr 11 11:48:45 forestcut volumio[712]: }
Apr 11 11:48:45 forestcut volumio[712]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 11 11:48:45 forestcut sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 11:47
Apr 11 11:48:45 forestcut sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"