-- Logs begin at Thu 2025-06-05 13:04:44 WIB, end at Fri 2025-06-06 01:18:52 WIB. -- Jun 06 01:17:14 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 01:17:18 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 01:17:18 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 06 01:17:20 volumio volumio[926]: info: Disabling plugin rotaryencoder2 Jun 06 01:17:20 volumio volumio[926]: info: Done. Jun 06 01:17:23 volumio go-librespot[20444]: time="2025-06-06T01:17:23+07:00" level=trace msg="received accesspoint ping" Jun 06 01:17:23 volumio go-librespot[20444]: time="2025-06-06T01:17:23+07:00" level=trace msg="received accesspoint pong ack" Jun 06 01:17:24 volumio go-librespot[20444]: time="2025-06-06T01:17:24+07:00" level=trace msg="sent dealer ping" Jun 06 01:17:24 volumio go-librespot[20444]: time="2025-06-06T01:17:24+07:00" level=trace msg="received dealer pong" Jun 06 01:17:24 volumio volumio[926]: info: Enabling plugin ampswitch Jun 06 01:17:24 volumio volumio[926]: info: Loading plugin "ampswitch"... Jun 06 01:17:24 volumio volumio[926]: info: Applying required configuration parameters for plugin ampswitch Jun 06 01:17:24 volumio volumio[926]: info: PLUGIN START: ampswitch Jun 06 01:17:24 volumio volumio[926]: info: Done. Jun 06 01:17:24 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:24 volumio volumio[926]: info: [ASDebug] CurState: pause PrevState: pause Jun 06 01:17:24 volumio volumio[926]: info: [ASDebug] CurState: pause PrevState: pause Jun 06 01:17:24 volumio volumio[926]: info: [ASDebug] CurState: pause PrevState: pause Jun 06 01:17:25 volumio volumio[926]: info: [ASDebug] Port: 24 Jun 06 01:17:25 volumio volumio[926]: info: [ASDebug] Inverted: false Jun 06 01:17:25 volumio volumio[926]: info: [ASDebug] Delay: 720 Jun 06 01:17:29 volumio sudo[11762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 01:17:29 volumio sudo[11762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 01:17:29 volumio sudo[11762]: pam_unix(sudo:session): session closed for user root Jun 06 01:17:29 volumio sudo[11765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 01:17:29 volumio sudo[11765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 01:17:29 volumio sudo[11765]: pam_unix(sudo:session): session closed for user root Jun 06 01:17:29 volumio volumio[926]: verbose: New Socket.io Connection to 192.168.1.172 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 14 Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::volumioGetVisibleSources Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 06 01:17:29 volumio volumio[926]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 06 01:17:29 volumio volumio[926]: info: Received Get System Info Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 01:17:29 volumio volumio[926]: info: Discovery: Getting this device information Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:29 volumio volumio[926]: info: Listing playlists Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 01:17:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 06 01:17:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 01:17:31 volumio volumio[926]: info: Received Get System Info Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 01:17:31 volumio volumio[926]: info: Discovery: Getting this device information Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:31 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 01:17:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 01:17:32 volumio volumio[926]: info: Received Get System Info Jun 06 01:17:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 01:17:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 01:17:32 volumio volumio[926]: info: Discovery: Getting this device information Jun 06 01:17:32 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 01:17:37 volumio volumio[926]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Jun 06 01:17:37 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 01:17:37 volumio volumio[926]: Cannot find translation for source YouTube Music Jun 06 01:17:37 volumio volumio[926]: Cannot find translation for source Spotify Jun 06 01:17:37 volumio volumio[926]: info: Disabling plugin youtube2 Jun 06 01:17:37 volumio volumio[926]: info: Done. Jun 06 01:17:39 volumio volumio[926]: info: [yt-cast-receiver] Player.reset() Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:39 volumio volumio[926]: verbose: UNSET VOLATILE: Service: spop Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::servicePushState Jun 06 01:17:39 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:17:39 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:17:39 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::servicePushState Jun 06 01:17:39 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:17:39 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:17:39 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:39 volumio volumio[926]: info: [ASDebug] CurState: pause PrevState: pause Jun 06 01:17:39 volumio volumio[926]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.n7aAtfvzGtQfTecd4mOmdJuLYDZ2.28ab6a8f63377981ba469c7ef0ef2027.state.track' Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: SPOTIFY VOLUME 47 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: VOLUMIO VOLUME 46 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: SPOTIFY VOLUME 47 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: VOLUMIO VOLUME 46 Jun 06 01:17:39 volumio volumio[926]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 06 01:17:39 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 06 01:17:39 volumio volumio[926]: info: Disabling plugin ytcr Jun 06 01:17:39 volumio volumio[926]: info: Done. Jun 06 01:17:39 volumio volumio[926]: info: [GPIO_Control] Status changed: pause Jun 06 01:17:39 volumio volumio[926]: info: [GPIO_Control] Status changed: pause Jun 06 01:17:41 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:17:41 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:17:41 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:17:41 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:17:44 volumio volumio[926]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Jun 06 01:17:44 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 01:17:44 volumio volumio[926]: Cannot find translation for source Spotify Jun 06 01:17:44 volumio volumio[926]: info: Disabling plugin ytmusic Jun 06 01:17:44 volumio volumio[926]: info: Done. Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 01:17:48 volumio volumio[926]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 06 01:17:48 volumio volumio[926]: info: Received Get System Version Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 01:17:48 volumio volumio[926]: info: Received Get System Info Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 01:17:48 volumio volumio[926]: info: Discovery: Getting this device information Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:48 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:17:48 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 01:17:51 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:51 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:17:53 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:17:53 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:17:53 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:17:53 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:17:53 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:17:53 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:17:54 volumio go-librespot[20444]: time="2025-06-06T01:17:54+07:00" level=trace msg="sent dealer ping" Jun 06 01:17:54 volumio go-librespot[20444]: time="2025-06-06T01:17:54+07:00" level=trace msg="received dealer pong" Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 06 01:17:54 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 06 01:17:58 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:17:58 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:00 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:18:00 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:18:00 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:18:00 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:18:00 volumio volumio[926]: info: Executing endpoint metavolumio Jun 06 01:18:00 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 01:18:24 volumio go-librespot[20444]: time="2025-06-06T01:18:24+07:00" level=trace msg="sent dealer ping" Jun 06 01:18:24 volumio go-librespot[20444]: time="2025-06-06T01:18:24+07:00" level=trace msg="received dealer pong" Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stPlaybackTimer Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:18:50 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:18:50 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::serviceStop Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::serviceStop Jun 06 01:18:50 volumio volumio[926]: info: Spotify Stop Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: SPOTIFY STOP Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: {"status":"pause","albumart":"/albumart","uri":"","trackType":"","codec":"","seek":0,"duration":0,"consume":false,"volume":46,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"ytcr"} Jun 06 01:18:50 volumio volumio[926]: info: Sending Spotify command to local API: /player/pause Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio volumio[926]: info: Airplay started streaming, receiving metadatas Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio shairport-sync[2887]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 06 01:18:50 volumio shairport-sync[2887]: Dload Upload Total Spent Left Speed Jun 06 01:18:50 volumio volumio[926]: info: [ASDebug] CurState: stop PrevState: pause Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: SPOTIFY VOLUME 47 Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: VOLUMIO VOLUME 46 Jun 06 01:18:50 volumio volumio[926]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 06 01:18:50 volumio go-librespot[20444]: time="2025-06-06T01:18:50+07:00" level=debug msg="pause track at 133636ms" Jun 06 01:18:50 volumio volumio[926]: info: Jun 06 01:18:50 volumio volumio[926]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 06 01:18:50 volumio volumio[926]: info: Airplay playback start Jun 06 01:18:50 volumio volumio[926]: info: CorePlayQueue::getTrack 10 Jun 06 01:18:50 volumio volumio[926]: info: CoreCommandRouter::volumioStop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::stop Jun 06 01:18:50 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 01:18:50 volumio shairport-sync[2887]: [158B blob data] Jun 06 01:18:50 volumio shairport-sync[2887]: {"time":1749147530861,"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 volumioOutput has buffer size 88200 Jun 06 01:18:50 volumio shairport-sync[2887]: 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 volumioOutput has buffer size 88200 Jun 06 01:18:50 volumio volumio[926]: info: [GPIO_Control] Status changed: stop Jun 06 01:18:50 volumio volumio[926]: info: [GPIO_Control] Handling event: musicStop Jun 06 01:18:50 volumio shairport-sync[2887]: 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 volumioOutput has buffer size 88200 Jun 06 01:18:50 volumio shairport-sync[2887]: 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 volumioOutput has buffer size 88200 Jun 06 01:18:51 volumio go-librespot[20444]: time="2025-06-06T01:18:51+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::servicePushState Jun 06 01:18:51 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::servicePushState Jun 06 01:18:51 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::servicePushState Jun 06 01:18:51 volumio volumio[926]: info: CoreStateMachine::pushState Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output update for this device Jun 06 01:18:51 volumio volumio[926]: info: MRS: Pushing multiroomSync output Jun 06 01:18:51 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Jun 06 01:18:51 volumio volumio[926]: info: [ASDebug] CurState: play PrevState: pause Jun 06 01:18:51 volumio volumio[926]: info: [ASDebug] Togle GPIO: ON Jun 06 01:18:51 volumio volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 01:18:51 volumio volumio[926]: Error: ESPIPE: invalid seek, write Jun 06 01:18:51 volumio volumio[926]: at Object.writeSync (fs.js:711:3) Jun 06 01:18:51 volumio volumio[926]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 06 01:18:51 volumio volumio[926]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Jun 06 01:18:51 volumio volumio[926]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Jun 06 01:18:51 volumio volumio[926]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jun 06 01:18:51 volumio volumio[926]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Jun 06 01:18:51 volumio volumio[926]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Jun 06 01:18:51 volumio volumio[926]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jun 06 01:18:51 volumio volumio[926]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jun 06 01:18:51 volumio volumio[926]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Jun 06 01:18:51 volumio volumio[926]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jun 06 01:18:51 volumio volumio[926]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jun 06 01:18:51 volumio volumio[926]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Jun 06 01:18:51 volumio volumio[926]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Jun 06 01:18:51 volumio volumio[926]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jun 06 01:18:51 volumio volumio[926]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Jun 06 01:18:51 volumio volumio[926]: errno: -29, Jun 06 01:18:51 volumio volumio[926]: syscall: 'write', Jun 06 01:18:51 volumio volumio[926]: code: 'ESPIPE' Jun 06 01:18:51 volumio volumio[926]: } Jun 06 01:18:51 volumio volumio[926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 01:18:52 volumio sudo[11982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-06 01:17 Jun 06 01:18:52 volumio sudo[11982]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"