-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Fri 2025-01-17 11:28:17 CST. -- Jan 17 11:27:00 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jan 17 11:27:00 volumio volumio[1092]: info: Received Get System Version Jan 17 11:27:00 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 11:27:00 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 11:27:02 volumio volumio[1092]: info: Received Get System Info Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 11:27:02 volumio volumio[1092]: info: Discovery: Getting this device information Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 11:27:02 volumio volumio[1092]: info: Received Get System Info Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 11:27:02 volumio volumio[1092]: info: Discovery: Getting this device information Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:02 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 11:27:10 volumio sudo[6117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 11:27:10 volumio sudo[6117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 11:27:10 volumio sudo[6117]: pam_unix(sudo:session): session closed for user root Jan 17 11:27:10 volumio sudo[6120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 11:27:10 volumio sudo[6120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 11:27:10 volumio sudo[6120]: pam_unix(sudo:session): session closed for user root Jan 17 11:27:10 volumio volumio[1092]: verbose: New Socket.io Connection to 192.168.1.172 from 192.168.1.10 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Jan 17 11:27:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 11:27:10 volumio volumio[1092]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 11:27:10 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:10 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 11:27:10 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 11:27:12 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 11:27:12 volumio volumio[1092]: info: Received Get System Info Jan 17 11:27:12 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 11:27:12 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 11:27:12 volumio volumio[1092]: info: Discovery: Getting this device information Jan 17 11:27:12 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:12 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 11:27:17 volumio sudo[6141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 11:27:17 volumio sudo[6141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 11:27:17 volumio sudo[6141]: pam_unix(sudo:session): session closed for user root Jan 17 11:27:17 volumio sudo[6144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 11:27:17 volumio sudo[6144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 11:27:17 volumio sudo[6144]: pam_unix(sudo:session): session closed for user root Jan 17 11:27:17 volumio volumio[1092]: verbose: New Socket.io Connection to 192.168.1.172 from 192.168.1.10 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Jan 17 11:27:17 volumio volumio[1092]: verbose: New Socket.io Connection to 53biw5xb7ghhxbxk1alrvhilkm22-f490a6bdc367fc9f223efd34eaeb39df.us1.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.2 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Jan 17 11:27:17 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 11:27:17 volumio volumio[1092]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 11:27:17 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:17 volumio volumio[1092]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 11:27:17 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 11:27:17 volumio volumio[1092]: info: Listing playlists Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 11:27:18 volumio volumio[1092]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 17 11:27:18 volumio volumio[1092]: info: Received Get System Version Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 11:27:18 volumio volumio[1092]: info: Received Get System Info Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 11:27:18 volumio volumio[1092]: info: Discovery: Getting this device information Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::volumioGetState Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 11:27:18 volumio volumio[1092]: info: VolumeController::SetAlsaVolume0 Jan 17 11:27:18 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:18 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:18 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:18 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:18 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:18 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:18 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:39 volumio volumio[1092]: info: MRS: Found cast device: BRAVIA-4K-VH21-258b228b0a79afe15736826a61abd204 Jan 17 11:27:39 volumio volumio[1092]: info: MRS: Found cast device: Google-Nest-Mini-1a8674ddd9060596c39a20b212b36dc9 Jan 17 11:27:42 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:42 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:42 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 48 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 1 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:42 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 1 Jan 17 11:27:42 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:42 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:42 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:42 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:42 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 2 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 1 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 2 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:42 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:42 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:42 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:42 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:42 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:42 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:42 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 1 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 3 Jan 17 11:27:42 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:42 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 3 Jan 17 11:27:42 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:42 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 3 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 4 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 3 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 5 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:43 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 5 Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 6 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 5 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 6 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 5 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 7 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:43 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 7 Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 8 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 7 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 8 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 9 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 7 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 9 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:43 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 9 Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 9 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 10 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:43 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:43 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:43 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:43 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 11 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 9 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 11 Jan 17 11:27:43 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:43 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 11 Jan 17 11:27:43 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:43 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:43 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:44 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:44 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:44 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:44 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:44 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:44 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 11 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 12 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:44 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:44 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:44 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:44 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:44 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:44 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:44 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:44 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:44 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 11 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 13 Jan 17 11:27:44 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:27:44 volumio volumio[1092]: info: Setting Spotify Volume from Volumio: 13 Jan 17 11:27:44 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:44 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:44 volumio volumio[1092]: info: VolumeController::SetAlsaVolume+ Jan 17 11:27:45 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:45 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:45 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:45 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:45 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:45 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:45 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jan 17 11:27:45 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 13 Jan 17 11:27:45 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 14 Jan 17 11:27:45 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:45 volumio volumio[1092]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Jan 17 11:27:45 volumio volumio[1092]: amixer: Unable to find simple control 'SoftMaster',0 Jan 17 11:27:46 volumio volumio[1092]: SPOTIFY: SETTING SPOTIFY VOLUME 13 Jan 17 11:27:46 volumio volumio[1092]: info: Sending Spotify command with payload to local API: /player/volume Jan 17 11:27:46 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:46 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:46 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:46 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:46 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:46 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:46 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:46 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:46 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:48 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:48 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:48 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:48 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:48 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:48 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:48 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:48 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:48 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:48 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:49 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:49 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:49 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:49 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:49 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:49 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:49 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:49 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:49 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:49 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:50 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:50 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:50 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:50 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:50 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:50 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:50 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:50 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:50 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:50 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:50 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:50 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:50 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:50 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:51 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:51 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:51 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:51 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:51 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:51 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:51 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:51 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:51 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:51 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:51 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:51 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:51 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:51 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:51 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:51 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:51 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:51 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:51 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:51 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:51 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:51 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:52 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:52 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:52 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:52 volumio volumio[1092]: info: sendMpdCommand play took 3 milliseconds Jan 17 11:27:52 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:52 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:52 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:52 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:52 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:52 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:52 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:52 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:52 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:52 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:52 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:52 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:27:52 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:52 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:52 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:52 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:27:52 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:27:52 volumio volumio[1092]: info: sendMpdCommand play took 0 milliseconds Jan 17 11:27:55 volumio volumio[1092]: info: CoreCommandRouter::volumioSeek Jan 17 11:27:55 volumio volumio[1092]: info: CoreStateMachine::seek Jan 17 11:27:55 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:55 volumio volumio[1092]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Soulfight%22%2C%22artist%22%3A%22The%20Revivalists%22%2C%22album%22%3A%22The%20Revivalists%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw=w701-h701-l90-rj","artist":"The Revivalists","album":"The Revivalists","name":"Soulfight","title":"Soulfight","duration":393,"samplerate":"VBR 160 kbps"} Jan 17 11:27:55 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:55 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:55 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:55 volumio volumio[1092]: info: ControllerMpd::seek Jan 17 11:27:55 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:55 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:55 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:55 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:55 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:55 volumio volumio[1092]: error: [2@0] {seek} Bad song index Jan 17 11:27:55 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:55 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jan 17 11:27:55 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 13 Jan 17 11:27:55 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 14 Jan 17 11:27:55 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:56 volumio volumio[1092]: info: CoreCommandRouter::volumioSeek Jan 17 11:27:56 volumio volumio[1092]: info: CoreStateMachine::seek Jan 17 11:27:56 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:56 volumio volumio[1092]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Soulfight%22%2C%22artist%22%3A%22The%20Revivalists%22%2C%22album%22%3A%22The%20Revivalists%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw=w701-h701-l90-rj","artist":"The Revivalists","album":"The Revivalists","name":"Soulfight","title":"Soulfight","duration":393,"samplerate":"VBR 160 kbps"} Jan 17 11:27:56 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:56 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:56 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:56 volumio volumio[1092]: info: ControllerMpd::seek Jan 17 11:27:56 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:56 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:56 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:57 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:57 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:57 volumio volumio[1092]: error: [2@0] {seek} Bad song index Jan 17 11:27:57 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 13 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 14 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:57 volumio volumio[1092]: info: CoreCommandRouter::volumioSeek Jan 17 11:27:57 volumio volumio[1092]: info: CoreStateMachine::seek Jan 17 11:27:57 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:57 volumio volumio[1092]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Soulfight%22%2C%22artist%22%3A%22The%20Revivalists%22%2C%22album%22%3A%22The%20Revivalists%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw=w701-h701-l90-rj","artist":"The Revivalists","album":"The Revivalists","name":"Soulfight","title":"Soulfight","duration":393,"samplerate":"VBR 160 kbps"} Jan 17 11:27:57 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:57 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:57 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:57 volumio volumio[1092]: info: ControllerMpd::seek Jan 17 11:27:57 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:57 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:57 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:57 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:57 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:57 volumio volumio[1092]: error: [2@0] {seek} Bad song index Jan 17 11:27:57 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 13 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 14 Jan 17 11:27:57 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:27:58 volumio volumio[1092]: info: CoreCommandRouter::volumioSeek Jan 17 11:27:58 volumio volumio[1092]: info: CoreStateMachine::seek Jan 17 11:27:58 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:58 volumio volumio[1092]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Soulfight%22%2C%22artist%22%3A%22The%20Revivalists%22%2C%22album%22%3A%22The%20Revivalists%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225f2zPv6Cnu4%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/2650NAYQZDItyNi0o42Tsxaeaym0W-7qH_Ct3xZsY_liAwTY1PxGDQfJpZXXxhqMzcG1KGXLNKYrfBTxEw=w701-h701-l90-rj","artist":"The Revivalists","album":"The Revivalists","name":"Soulfight","title":"Soulfight","duration":393,"samplerate":"VBR 160 kbps"} Jan 17 11:27:58 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:27:58 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:27:58 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:27:58 volumio volumio[1092]: info: ControllerMpd::seek Jan 17 11:27:58 volumio volumio[1092]: info: CoreStateMachine::pushState Jan 17 11:27:58 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:27:58 volumio volumio[1092]: info: CoreCommandRouter::volumioPushState Jan 17 11:27:58 volumio volumio[1092]: info: MRS: Pushing multiroomSync output update for this device Jan 17 11:27:58 volumio volumio[1092]: info: MRS: Pushing multiroomSync output Jan 17 11:27:58 volumio volumio[1092]: error: [2@0] {seek} Bad song index Jan 17 11:27:58 volumio volumio[1092]: info: [ASDebug] CurState: pause PrevState: pause Jan 17 11:27:58 volumio volumio[1092]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jan 17 11:27:58 volumio volumio[1092]: SPOTIFY: SPOTIFY VOLUME 13 Jan 17 11:27:58 volumio volumio[1092]: SPOTIFY: VOLUMIO VOLUME 14 Jan 17 11:27:58 volumio volumio[1092]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 17 11:28:09 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 17 11:28:09 volumio volumio[1092]: info: Playing Remote Device: http://192.168.1.172 Jan 17 11:28:09 volumio volumio[1092]: info: Jan 17 11:28:09 volumio volumio[1092]: ---------------------------- Client requests Volumio play Jan 17 11:28:09 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:28:09 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:28:09 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:28:09 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:28:09 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:28:09 volumio volumio[1092]: info: sendMpdCommand play took 3 milliseconds Jan 17 11:28:09 volumio volumio[1092]: info: Done playing: Jan 17 11:28:09 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 17 11:28:09 volumio volumio[1092]: info: Playing Remote Device: http://192.168.1.172 Jan 17 11:28:09 volumio volumio[1092]: info: Jan 17 11:28:09 volumio volumio[1092]: ---------------------------- Client requests Volumio play Jan 17 11:28:09 volumio volumio[1092]: info: CoreCommandRouter::volumioPlay Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::play index undefined Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:28:09 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::startPlaybackTimer Jan 17 11:28:09 volumio volumio[1092]: info: CorePlayQueue::getTrack 0 Jan 17 11:28:09 volumio volumio[1092]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:28:09 volumio volumio[1092]: info: ControllerMpd::resume Jan 17 11:28:09 volumio volumio[1092]: verbose: ControllerMpd::sendMpdCommand play Jan 17 11:28:09 volumio volumio[1092]: info: sendMpdCommand play took 1 milliseconds Jan 17 11:28:09 volumio volumio[1092]: info: Done playing: Jan 17 11:28:17 volumio volumio[1092]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jan 17 11:28:17 volumio volumio[1092]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jan 17 11:28:17 volumio volumio[1092]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 11:28:17 volumio volumio[1092]: TypeError: Cannot read property 'then' of undefined Jan 17 11:28:17 volumio volumio[1092]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jan 17 11:28:17 volumio volumio[1092]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2273:30) Jan 17 11:28:17 volumio volumio[1092]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Jan 17 11:28:17 volumio volumio[1092]: at Socket.emit (events.js:315:20) Jan 17 11:28:17 volumio volumio[1092]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 17 11:28:17 volumio volumio[1092]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 17 11:28:17 volumio volumio[1092]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 11:28:17 volumio sudo[6317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 11:27 Jan 17 11:28:17 volumio sudo[6317]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"