-- Logs begin at Thu 2019-02-14 11:12:06 CET, end at Fri 2024-10-11 21:29:34 CEST. -- Oct 11 21:28:17 bad sudo[5804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:28:17 bad sudo[5804]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:17 bad sudo[5806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:28:17 bad sudo[5806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:17 bad sudo[5806]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:17 bad sudo[5804]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:17 bad volumio[816]: verbose: New Socket.io Connection to 192.168.0.150 from 192.168.0.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Oct 11 21:28:18 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:28:18 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 21:28:19 bad sudo[5814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:28:19 bad sudo[5814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:20 bad sudo[5816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:28:20 bad sudo[5816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:20 bad sudo[5814]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:20 bad sudo[5816]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:20 bad volumio[816]: verbose: New Socket.io Connection to 192.168.0.150 from 192.168.0.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:28:20 bad volumio[816]: info: Received Get System Info Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:28:20 bad volumio[816]: info: Discovery: Getting this device information Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:20 bad volumio[816]: info: Listing playlists Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 11 21:28:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:28:21 bad volumio[816]: info: Received Get System Info Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:28:21 bad volumio[816]: info: Discovery: Getting this device information Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:21 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:28:22 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 11 21:28:23 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:28:23 bad volumio[816]: info: Received Get System Info Oct 11 21:28:23 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:28:23 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:28:23 bad volumio[816]: info: Discovery: Getting this device information Oct 11 21:28:23 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:23 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 21:28:26 bad volumio[816]: info: Getting Alsa Cards List without I2S DAC Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 11 21:28:26 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 11 21:28:27 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 21:28:30 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 21:28:38 bad volumio[816]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 11 21:28:38 bad volumio[816]: info: Received Get System Version Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 21:28:38 bad volumio[816]: info: Received Get System Info Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:28:38 bad volumio[816]: info: Discovery: Getting this device information Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:38 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:28:42 bad volumio[816]: info: CoreCommandRouter::volumioGetState Oct 11 21:28:43 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:43 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:43 bad volumio[816]: info: Retrieving Cloud Streaming UI Oct 11 21:28:44 bad volumio[816]: info: Getting Tidal Cloud Configuration Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:44 bad volumio[816]: info: Getting Qobuz Cloud Configuration Oct 11 21:28:44 bad volumio[816]: info: Asking plugin for UI Config Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:44 bad volumio[816]: info: Getting Spotify Cloud Configuration Oct 11 21:28:44 bad volumio[816]: info: Asking plugin for UI Config Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:44 bad volumio[816]: info: Saving Spotify Acccount Oct 11 21:28:44 bad volumio[816]: info: Got Tidal Cloud Configuration Oct 11 21:28:44 bad volumio[816]: info: Got it Oct 11 21:28:44 bad volumio[816]: info: Got it Oct 11 21:28:44 bad volumio[816]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:28:44 bad volumio[816]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:28:44 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 11 21:28:48 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:28:51 bad volumio[816]: info: Disabling MyMusic plugin airplay_emulation Oct 11 21:28:51 bad volumio[816]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 11 21:28:51 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:28:51 bad volumio[816]: Cannot find translation for source Spotify Oct 11 21:28:51 bad volumio[816]: info: Disabling plugin airplay_emulation Oct 11 21:28:51 bad volumio[816]: info: Done. Oct 11 21:28:51 bad sudo[5834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 11 21:28:51 bad sudo[5834]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:51 bad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:28:51 bad shairport-sync[3615]: {"time":1728674849158,"response":"startAirplayPlayback Success"} Oct 11 21:28:51 bad systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:28:51 bad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:28:51 bad sudo[5834]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:51 bad volumio[816]: info: Shairport-Sync Stopped Oct 11 21:28:52 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:28:53 bad go-librespot[4012]: time="2024-10-11T21:28:53+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 342" Oct 11 21:28:53 bad go-librespot[4012]: time="2024-10-11T21:28:53+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 11 21:28:53 bad go-librespot[4012]: time="2024-10-11T21:28:53+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 11 21:28:53 bad go-librespot[4012]: time="2024-10-11T21:28:53+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1820" Oct 11 21:28:54 bad go-librespot[4012]: time="2024-10-11T21:28:54+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 250" Oct 11 21:28:54 bad go-librespot[4012]: time="2024-10-11T21:28:54+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 11 21:28:56 bad volumio[816]: info: Enabling MyMusic plugin airplay_emulation Oct 11 21:28:56 bad volumio[816]: info: Enabling plugin airplay_emulation Oct 11 21:28:56 bad volumio[816]: info: Loading plugin "airplay_emulation"... Oct 11 21:28:56 bad volumio[816]: info: Starting Shairport Sync Oct 11 21:28:56 bad volumio[816]: info: PLUGIN START: airplay_emulation Oct 11 21:28:56 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:28:56 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:28:56 bad volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 21:28:56 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:28:56 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:28:56 bad volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 21:28:56 bad volumio[816]: info: Done. Oct 11 21:28:56 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:28:56 bad volumio[816]: info: Starting Shairport Sync Oct 11 21:28:56 bad volumio[816]: info: Starting Shairport Sync Oct 11 21:28:56 bad sudo[5844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:28:56 bad sudo[5844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:56 bad sudo[5846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:28:56 bad sudo[5846]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:28:57 bad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 21:28:57 bad sudo[5844]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:57 bad volumio[816]: info: Shairport-Sync Started Oct 11 21:28:57 bad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:28:57 bad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 21:28:57 bad systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:28:57 bad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:28:57 bad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 21:28:57 bad sudo[5846]: pam_unix(sudo:session): session closed for user root Oct 11 21:28:57 bad volumio[816]: info: Shairport-Sync Started Oct 11 21:28:57 bad volumio[816]: (node:816) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 21:28:57 bad volumio[816]: (node:816) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 21:28:57 bad volumio[816]: (node:816) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 21:28:57 bad volumio[816]: (node:816) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 21:28:57 bad volumio[816]: (node:816) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 21:29:00 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:04 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad volumio[816]: info: Airplay started streaming, receiving metadatas Oct 11 21:29:07 bad shairport-sync[5850]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 21:29:07 bad shairport-sync[5850]: Dload Upload Total Spent Left Speed Oct 11 21:29:07 bad volumio[816]: info: Oct 11 21:29:07 bad volumio[816]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 11 21:29:07 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 11 21:29:07 bad volumio[816]: info: Airplay playback start Oct 11 21:29:07 bad shairport-sync[5850]: [158B blob data] Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:08 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:08 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:08 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:08 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:09 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:09 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:10 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:10 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:10 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:10 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:10 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:10 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:10 bad go-librespot[4012]: time="2024-10-11T21:29:10+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 315" Oct 11 21:29:10 bad go-librespot[4012]: time="2024-10-11T21:29:10+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1789" Oct 11 21:29:10 bad go-librespot[4012]: time="2024-10-11T21:29:10+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 11 21:29:10 bad go-librespot[4012]: time="2024-10-11T21:29:10+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 11 21:29:11 bad go-librespot[4012]: time="2024-10-11T21:29:11+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 11 21:29:11 bad go-librespot[4012]: time="2024-10-11T21:29:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1376" Oct 11 21:29:12 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:16 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:19 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:19 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:20 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::servicePushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad shairport-sync[5850]: {"time":1728674947865,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 21:29:22 bad shairport-sync[5850]: Dload Upload Total Spent Left Speed Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:22 bad volumio[816]: info: Oct 11 21:29:22 bad volumio[816]: ---------------------------- Client requests Stop Airplay Playback Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 11 21:29:22 bad volumio[816]: info: Airplay playback stop Oct 11 21:29:22 bad volumio[816]: info: Airplay Playback Stopped, clearing state Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::resetVolumioState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::getcurrentVolume Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 21:29:22 bad shairport-sync[5850]: [158B blob data] Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:22 bad volumio[816]: info: CoreStateMachine::updateTrackBlock Oct 11 21:29:22 bad volumio[816]: info: CorePlayQueue::getTrackBlock Oct 11 21:29:22 bad volumio[816]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 21:29:23 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:23 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:23 bad volumio[816]: info: CoreStateMachine::pushState Oct 11 21:29:23 bad volumio[816]: info: CoreCommandRouter::volumioPushState Oct 11 21:29:23 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:23 bad volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 11 21:29:23 bad volumio[816]: info: Disabling MyMusic plugin upnp Oct 11 21:29:23 bad sudo[5916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 11 21:29:23 bad sudo[5916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:29:23 bad volumio[816]: error: Upnp client error: Error: This socket has been ended by the other party Oct 11 21:29:23 bad systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 11 21:29:24 bad volumio[816]: info: Disabling MyMusic plugin airplay_emulation Oct 11 21:29:24 bad volumio[816]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 11 21:29:24 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:29:24 bad volumio[816]: Cannot find translation for source Spotify Oct 11 21:29:24 bad volumio[816]: info: Disabling plugin airplay_emulation Oct 11 21:29:24 bad volumio[816]: info: Done. Oct 11 21:29:24 bad sudo[5919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 11 21:29:24 bad sudo[5919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:29:24 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:24 bad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:29:24 bad shairport-sync[5850]: {"time":1728674962645,"response":"stopAirplayPlayback Success"} Oct 11 21:29:24 bad systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:29:24 bad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:29:24 bad sudo[5919]: pam_unix(sudo:session): session closed for user root Oct 11 21:29:24 bad volumio[816]: info: Shairport-Sync Stopped Oct 11 21:29:25 bad volumio[816]: info: Disabling MyMusic plugin upnp_browser Oct 11 21:29:25 bad volumio[816]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedienserver Oct 11 21:29:25 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:29:25 bad volumio[816]: Cannot find translation for source Spotify Oct 11 21:29:25 bad volumio[816]: info: Disabling plugin upnp_browser Oct 11 21:29:25 bad volumio[816]: info: Done. Oct 11 21:29:27 bad volumio[816]: info: Disabling MyMusic plugin upnp Oct 11 21:29:27 bad sudo[5922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 11 21:29:27 bad sudo[5922]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:29:28 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 21:29:32 bad volumio[816]: info: Enabling MyMusic plugin upnp Oct 11 21:29:32 bad volumio[816]: info: Enabling plugin upnp Oct 11 21:29:32 bad volumio[816]: info: Loading plugin "upnp"... Oct 11 21:29:32 bad volumio[816]: info: [1728674972057] Starting Upmpd Daemon Oct 11 21:29:32 bad volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 21:29:32 bad volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 21:29:32 bad volumio[816]: Error: listen EADDRINUSE: address already in use :::6599 Oct 11 21:29:32 bad volumio[816]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 11 21:29:32 bad volumio[816]: at listenInCluster (net.js:1366:12) Oct 11 21:29:32 bad volumio[816]: at Server.listen (net.js:1452:7) Oct 11 21:29:32 bad volumio[816]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 11 21:29:32 bad volumio[816]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 11 21:29:32 bad volumio[816]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 11 21:29:32 bad volumio[816]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 11 21:29:32 bad volumio[816]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 11 21:29:32 bad volumio[816]: code: 'EADDRINUSE', Oct 11 21:29:32 bad volumio[816]: errno: -98, Oct 11 21:29:32 bad volumio[816]: syscall: 'listen', Oct 11 21:29:32 bad volumio[816]: address: '::', Oct 11 21:29:32 bad volumio[816]: port: 6599 Oct 11 21:29:32 bad volumio[816]: } Oct 11 21:29:32 bad volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 21:29:34 bad sudo[5933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 21:28 Oct 11 21:29:34 bad sudo[5933]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"