-- Logs begin at Fri 2025-06-20 21:49:12 JST, end at Fri 2025-06-20 21:56:21 JST. -- Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184331 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183819 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R185099 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184075 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R183051 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184843 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184587 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R185611 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1305R94987 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R70923 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R71947 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1894R130059 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140555 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140811 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R141323 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$11$1966R139275 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1661R104459 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R172043 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R171531 in service upnp_browser Jun 20 21:55:00 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R301067 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$86$118R1291 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R43019 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R44043 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R8203 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R6155 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7435 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7691 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$293$294R3851 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P1R304907 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R305163 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P11R305419 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P9R305931 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P8R306187 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P7R306443 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P6R306699 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P3R307467 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P4R307211 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R308235 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R315659 in service upnp_browser Jun 20 21:55:01 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P19R325643 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P16R326411 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P15R326667 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54027 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54283 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249099 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249611 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250123 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250379 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251147 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251403 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R79371 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78859 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R77835 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75531 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75787 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76811 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76555 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78603 in service upnp_browser Jun 20 21:55:02 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76043 in service upnp_browser Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:55:10 rivoplus volumio[3414]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 20 21:55:10 rivoplus volumio[3414]: info: Received Get System Version Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 21:55:10 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:10 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:17 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:17 rivoplus sudo[5688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:55:17 rivoplus sudo[5688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:17 rivoplus sudo[5688]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:17 rivoplus sudo[5691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:55:17 rivoplus sudo[5691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:17 rivoplus sudo[5691]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:17 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 20 21:55:17 rivoplus sudo[5696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:55:17 rivoplus sudo[5696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:17 rivoplus sudo[5696]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:17 rivoplus sudo[5699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:55:17 rivoplus sudo[5699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:18 rivoplus sudo[5699]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:18 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetQueue Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreStateMachine::getQueue Jun 20 21:55:18 rivoplus volumio[3414]: info: CorePlayQueue::getQueue Jun 20 21:55:18 rivoplus volumio[3414]: info: Listing playlists Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 21:55:18 rivoplus volumio[3414]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 21:55:18 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:18 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:18 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:55:19 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:19 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:19 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:55:20 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:20 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:20 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:21 rivoplus volumio[3414]: info: Preload queue cleared Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::ClearQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::stop Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::serviceStop Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreCommandRouter::serviceStop Jun 20 21:55:21 rivoplus volumio[3414]: info: Airplay Stop Jun 20 21:55:21 rivoplus volumio[3414]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::clearPlayQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::saveQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::addQueueItems Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::addQueueItems Jun 20 21:55:21 rivoplus volumio[3414]: info: Preload queue cleared Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285707 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285707 Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::saveQueue Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::updateTrackBlock Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::getTrackBlock Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPlay Jun 20 21:55:21 rivoplus volumio[3414]: verbose: UNSET VOLATILE: Service: airplay_emulation Jun 20 21:55:21 rivoplus volumio[3414]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::play index 0 Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::addQueueItems Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::addQueueItems Jun 20 21:55:21 rivoplus volumio[3414]: info: Preload queue cleared Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285195 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285195 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285451 Jun 20 21:55:21 rivoplus sudo[5710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 5268 Jun 20 21:55:21 rivoplus sudo[5710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285451 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285963 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R285963 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286219 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286219 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286475 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286475 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286731 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286731 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R286987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R287243 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3086R287243 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R140555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R140555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R140811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R140811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R141323 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1991R141323 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1009R75275 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1009R75275 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188939 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188939 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R190219 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R190219 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188683 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188683 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R189707 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R189707 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R189451 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R189451 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188171 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188171 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188427 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R188427 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R190987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2289R190987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R146699 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R146699 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R145931 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R145931 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R146443 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2013R146443 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R260619 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R260619 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R260875 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R260875 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R261387 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2916R261387 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R92171 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R92171 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R91915 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R91915 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R91147 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R91147 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R90635 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1155R90635 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R227595 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R227595 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R228363 Jun 20 21:55:21 rivoplus sudo[5710]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R228363 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R228875 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R228875 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R229131 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2561R229131 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3013R278795 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3013R278795 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$423R8715 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$423R8715 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2417$2418R211467 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2417$2418R211467 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183563 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183563 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183307 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183307 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R185355 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R185355 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184331 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184331 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183819 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R183819 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R185099 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R185099 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184075 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$2231$2267R184075 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R183051 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R183051 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184843 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184843 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184587 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R184587 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R185611 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$84R185611 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1305R94987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1305R94987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R70923 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R70923 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R71947 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$850R71947 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1894R130059 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1894R130059 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R140811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R141323 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1990R141323 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$11$1966R139275 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$11$1966R139275 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1661R104459 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1661R104459 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R172043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R172043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R171531 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$2200$2201R171531 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R301067 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R301067 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$86$118R1291 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$86$118R1291 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R43019 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R43019 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R44043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$424$729$730R44043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R8203 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R8203 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R6155 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R6155 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7435 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7435 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7691 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$13$85$378$379R7691 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$293$294R3851 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$293$294R3851 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P1R304907 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P1R304907 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R305163 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R305163 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P11R305419 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P11R305419 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P9R305931 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P9R305931 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P8R306187 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P8R306187 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P7R306443 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P7R306443 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P6R306699 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P6R306699 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P3R307467 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P3R307467 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P4R307211 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P4R307211 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R308235 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R308235 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R315659 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P0R315659 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P19R325643 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P19R325643 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P16R326411 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P16R326411 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P15R326667 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P15R326667 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54027 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54027 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54283 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$785R54283 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249099 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249099 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249611 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2846R249611 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250123 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250123 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250379 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R250379 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251147 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251147 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251403 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2843R251403 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R79371 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R79371 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78859 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78859 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R77835 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R77835 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75531 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75531 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75787 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R75787 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78603 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R78603 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Using cached record of: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$1027R76043 Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R245515 Jun 20 21:55:21 rivoplus sudo[5713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 5268 Jun 20 21:55:21 rivoplus sudo[5713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R245515 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R246795 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R246795 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R247051 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R247051 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R247307 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R247307 in service upnp_browser Jun 20 21:55:21 rivoplus sudo[5713]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248331 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248331 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248587 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248587 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248843 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$16$1026$2832R248843 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R318987 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R318987 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R319499 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R319499 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R315659 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R315659 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316171 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316171 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316683 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316683 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316939 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R316939 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R317195 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3302R317195 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R196363 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R196363 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R197643 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R197643 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R198411 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R198411 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R196875 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R196875 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R198667 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$2323R198667 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1854R125451 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$1854R125451 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R319755 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R319755 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320011 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320011 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320267 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320267 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320523 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3318R320523 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R308491 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R308491 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R308747 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R308747 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309003 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309003 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309259 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309259 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309515 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309515 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309771 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R309771 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R310539 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R310539 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R310795 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R310795 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311051 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311051 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311307 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311307 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311563 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311563 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311819 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3251R311819 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P3R331019 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P3R331019 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P2R331275 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P2R331275 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P1R331531 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P1R331531 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P5R330763 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$55P5R330763 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332299 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332299 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332555 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332555 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332811 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R332811 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333067 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333067 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333323 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333323 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333579 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333579 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333835 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R333835 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R334091 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R334091 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: Adding Item to queue: upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R334347 Jun 20 21:55:21 rivoplus volumio[3414]: info: Exploding uri upnp/http://192.168.1.11:9000/dev0/srv1/control@0$1$12$3426R334347 in service upnp_browser Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::stop Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::play index undefined Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 21:55:21 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:21 rivoplus volumio[3414]: info: CoreStateMachine::startPlaybackTimer Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 20 21:55:22 rivoplus volumio[3414]: info: [1750424122003] ControllerUPNPBrowser::clearAddPlayTrack Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand stop took 9 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 21:55:22 rivoplus volumio[3414]: info: Shairport-Sync paused with USR2 Jun 20 21:55:22 rivoplus volumio[3414]: info: Shairport-Sync paused with USR2 Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand clear took 21 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav" Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: error: updateQueue error: null Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 8ms Jun 20 21:55:22 rivoplus volumio[3414]: error: updateQueue error: null Jun 20 21:55:22 rivoplus volumio[3414]: error: updateQueue error: null Jun 20 21:55:22 rivoplus volumio[3414]: error: updateQueue error: null Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav" Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 325ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 323ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 322ms Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand add "http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav" took 3 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand play Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces system playlist update Jun 20 21:55:22 rivoplus volumio[3414]: info: Ignoring MPD Status Update Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 8ms Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand play took 7 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 6ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 4ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 3ms Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 11 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 9 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 8 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 6 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: Jun 20 21:55:22 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::getState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 18 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 12 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 6 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand status took 4 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus stop Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 80ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 147ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 149ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 149ms Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 126 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 124 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 124 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 124 milliseconds Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ControllerMpd::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jun 20 21:55:22 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I285707.wav","artist":null,"album":null,"uri":"http://192.168.1.11:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I285707.wav","trackType":"wav"} Jun 20 21:55:22 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService play Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jun 20 21:55:22 rivoplus volumio[3414]: info: Received an update from plugin. extracting info from payload Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jun 20 21:55:22 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jun 20 21:55:22 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 334ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 327ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 324ms Jun 20 21:55:22 rivoplus volumio[3414]: info: ------------------------------ 323ms Jun 20 21:55:22 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:22 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:22 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:22 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:22 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Signalling Playback active due to playback status change Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Updating RAAT Signal Path Jun 20 21:55:23 rivoplus volumio[3414]: info: Restarting Shairport-Sync after stop Jun 20 21:55:23 rivoplus volumio[3414]: info: Restarting Shairport-Sync after stop Jun 20 21:55:23 rivoplus sudo[5718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 21:55:23 rivoplus sudo[5718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:23 rivoplus sudo[5721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 21:55:23 rivoplus sudo[5721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:23 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 20 21:55:23 rivoplus shairport-sync[5268]: {"time":1750424080171,"response":"stopAirplayPlayback Success"} Jun 20 21:55:23 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jun 20 21:55:23 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 20 21:55:23 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:55:23 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 21:55:23 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 20 21:55:23 rivoplus sudo[5718]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:23 rivoplus volumio[3414]: info: Shairport-Sync retarted Jun 20 21:55:23 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 20 21:55:23 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 20 21:55:23 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jun 20 21:55:23 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:55:23 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 20 21:55:23 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 20 21:55:23 rivoplus sudo[5721]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:23 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 21:55:23 rivoplus volumio[3414]: info: Shairport-Sync retarted Jun 20 21:55:24 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jun 20 21:55:24 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 21:55:28 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 21:55:35 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushQueue Jun 20 21:55:35 rivoplus volumio[3414]: info: CorePlayQueue::saveQueue Jun 20 21:55:35 rivoplus volumio[3414]: info: CoreStateMachine::updateTrackBlock Jun 20 21:55:35 rivoplus volumio[3414]: info: CorePlayQueue::getTrackBlock Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:55:36 rivoplus volumio[3414]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 20 21:55:36 rivoplus volumio[3414]: info: Received Get System Version Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 21:55:36 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:36 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:37 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:38 rivoplus sudo[5773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:55:38 rivoplus sudo[5773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:38 rivoplus sudo[5773]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:38 rivoplus sudo[5776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:55:38 rivoplus sudo[5776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:38 rivoplus sudo[5776]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:38 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 21:55:38 rivoplus sudo[5781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:55:38 rivoplus sudo[5781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:38 rivoplus sudo[5781]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 21:55:38 rivoplus sudo[5784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:55:38 rivoplus sudo[5784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:38 rivoplus sudo[5784]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:38 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetQueue Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreStateMachine::getQueue Jun 20 21:55:38 rivoplus volumio[3414]: info: CorePlayQueue::getQueue Jun 20 21:55:38 rivoplus volumio[3414]: info: Listing playlists Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 21:55:38 rivoplus volumio[3414]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 21:55:38 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:38 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:55:38 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:55:40 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:40 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:40 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:41 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:55:41 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:41 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:41 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:41 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:41 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:41 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:48 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:48 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:48 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:48 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:48 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:48 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 21:55:51 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: Starting browser stream Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: Setting this device as Streaming Server Jun 20 21:55:51 rivoplus volumio[3414]: info: Jun 20 21:55:51 rivoplus volumio[3414]: [1750424151019] ---------------------------- MRS: Setting Streaming Server Jun 20 21:55:51 rivoplus volumio[3414]: info: Enabled audio output: browserPlayback Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: enable multiroom server output Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: Set multiroom target PCM to volumioMultiRoom Jun 20 21:55:51 rivoplus volumio[3414]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Jun 20 21:55:51 rivoplus volumio[3414]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: STARTING volumioStreaming Jun 20 21:55:51 rivoplus sudo[5817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 20 21:55:51 rivoplus sudo[5817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:51 rivoplus sudo[5817]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:51 rivoplus sudo[5820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Jun 20 21:55:51 rivoplus sudo[5820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:51 rivoplus systemd[1]: Started VolumioStreamingService. Jun 20 21:55:51 rivoplus sudo[5820]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:51 rivoplus volumio[3414]: info: MRS: volumioStreaming STARTED Jun 20 21:55:51 rivoplus sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 20 21:55:51 rivoplus sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:51 rivoplus sudo[5827]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:53 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Jun 20 21:55:53 rivoplus volumio[3414]: info: MRS: Starting browser stream Jun 20 21:55:53 rivoplus volumio[3414]: info: MRS: Setting this device as single Jun 20 21:55:53 rivoplus volumio[3414]: info: Jun 20 21:55:53 rivoplus volumio[3414]: [1750424153734] ---------------------------- Setting Multiroom Single Jun 20 21:55:53 rivoplus volumio[3414]: info: MRS: disable multiroom output Jun 20 21:55:53 rivoplus volumio[3414]: info: MRS: Set multiroom target PCM to volumioDiscard Jun 20 21:55:53 rivoplus volumio[3414]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Jun 20 21:55:54 rivoplus volumio[3414]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Jun 20 21:55:54 rivoplus volumio[3414]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Jun 20 21:55:55 rivoplus volumio[3414]: info: MRS: STOPPING volumioStreaming Jun 20 21:55:55 rivoplus sudo[5838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 20 21:55:55 rivoplus sudo[5838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:55 rivoplus sudo[5840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream2.ts Jun 20 21:55:55 rivoplus sudo[5840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:55 rivoplus systemd[1]: Stopping VolumioStreamingService... Jun 20 21:55:55 rivoplus systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Jun 20 21:55:55 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:55:55 rivoplus sudo[5840]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:55 rivoplus volumio[3414]: info: MRS: Removed streaming files Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:55:57 rivoplus volumio[3414]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 20 21:55:57 rivoplus volumio[3414]: info: Received Get System Version Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 21:55:57 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:55:57 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:57 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:55:59 rivoplus volumio[3414]: error: error Jun 20 21:55:59 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:55:59 rivoplus sudo[5861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:55:59 rivoplus sudo[5861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:59 rivoplus sudo[5861]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:59 rivoplus sudo[5865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:55:59 rivoplus sudo[5865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:55:59 rivoplus sudo[5865]: pam_unix(sudo:session): session closed for user root Jun 20 21:55:59 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 20 21:56:00 rivoplus sudo[5869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 21:56:00 rivoplus sudo[5869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:00 rivoplus sudo[5869]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:00 rivoplus sudo[5873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 21:56:00 rivoplus sudo[5873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:00 rivoplus sudo[5873]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:00 rivoplus volumio[3414]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 13; SO-51B Build/61.2.C.0.161; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetQueue Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreStateMachine::getQueue Jun 20 21:56:00 rivoplus volumio[3414]: info: CorePlayQueue::getQueue Jun 20 21:56:00 rivoplus volumio[3414]: info: Listing playlists Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 21:56:00 rivoplus volumio[3414]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 21:56:00 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:56:00 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:56:00 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 21:56:01 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:56:02 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:56:02 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 21:56:02 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:56:02 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:02 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: Retrieving Cloud Streaming UI Jun 20 21:56:09 rivoplus volumio[3414]: info: Getting Tidal Cloud Configuration Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: Getting Qobuz Cloud Configuration Jun 20 21:56:09 rivoplus volumio[3414]: info: Asking plugin for UI Config Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: Getting Spotify Cloud Configuration Jun 20 21:56:09 rivoplus volumio[3414]: info: Asking plugin for UI Config Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: Saving Spotify Acccount Jun 20 21:56:09 rivoplus volumio[3414]: info: Got it Jun 20 21:56:09 rivoplus volumio[3414]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 20 21:56:09 rivoplus volumio[3414]: info: Got Tidal Cloud Configuration Jun 20 21:56:09 rivoplus volumio[3414]: info: Got it Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetBrowseSources Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetBrowseSources Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetBrowseSources Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 20 21:56:09 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 20 21:56:10 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 21:56:11 rivoplus volumio[3414]: info: Disabling MyMusic plugin bluetooth Jun 20 21:56:12 rivoplus sudo[5911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Jun 20 21:56:12 rivoplus sudo[5911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:12 rivoplus systemd[1]: Stopping Volumio Bluetooth Module... Jun 20 21:56:12 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:56:12 rivoplus volumiobt[5913]: Killing pulse Jun 20 21:56:12 rivoplus volumiobt[5913]: Killing BT Pair agent Jun 20 21:56:12 rivoplus bluetoothd[3313]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jun 20 21:56:12 rivoplus bluetoothd[3313]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jun 20 21:56:12 rivoplus systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Jun 20 21:56:12 rivoplus systemd[1]: volumiobt.service: Succeeded. Jun 20 21:56:12 rivoplus systemd[1]: Stopped Volumio Bluetooth Module. Jun 20 21:56:12 rivoplus sudo[5911]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:12 rivoplus volumio[3414]: info: Volumio BT Module successfully stopped Jun 20 21:56:12 rivoplus volumio[3414]: info: Disabling plugin bluetooth Jun 20 21:56:12 rivoplus volumio[3414]: info: Done. Jun 20 21:56:13 rivoplus volumio[3414]: info: Enabling MyMusic plugin bluetooth Jun 20 21:56:13 rivoplus volumio[3414]: info: Enabling plugin bluetooth Jun 20 21:56:13 rivoplus volumio[3414]: info: Loading plugin "bluetooth"... Jun 20 21:56:13 rivoplus volumio[3414]: info: [1750424173500] Starting BluetoothController Jun 20 21:56:13 rivoplus volumio[3414]: info: PLUGIN START: bluetooth Jun 20 21:56:13 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 21:56:13 rivoplus volumio[3414]: info: Done. Jun 20 21:56:13 rivoplus sudo[5917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivoplus Jun 20 21:56:13 rivoplus sudo[5917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:13 rivoplus sudo[5917]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:13 rivoplus volumio[3414]: info: Bluetooth name changed to Rivoplus Jun 20 21:56:13 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 20 21:56:16 rivoplus sudo[5937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 20 21:56:16 rivoplus sudo[5937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:16 rivoplus systemd[1]: Started Volumio Bluetooth Module. Jun 20 21:56:16 rivoplus sudo[5937]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:16 rivoplus volumio[3414]: info: Volumio BT Module successfully started Jun 20 21:56:16 rivoplus sudo[5942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 20 21:56:16 rivoplus sudo[5942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:16 rivoplus volumiobt[5939]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 20 21:56:16 rivoplus sudo[5942]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:17 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 20 21:56:17 rivoplus sudo[5994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 20 21:56:17 rivoplus sudo[5994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:17 rivoplus volumiobt[5939]: pulseaudio: no process found Jun 20 21:56:17 rivoplus sudo[5994]: pam_unix(sudo:session): session closed for user root Jun 20 21:56:18 rivoplus pulseaudio[5998]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 20 21:56:18 rivoplus pulseaudio[5998]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 20 21:56:18 rivoplus volumiobt[5939]: Applying permissions Jun 20 21:56:18 rivoplus volumiobt[5939]: Setting BT discoverable and pairable Jun 20 21:56:18 rivoplus bluetoothd[3313]: Endpoint registered: sender=:1.79 path=/MediaEndpoint/A2DPSource Jun 20 21:56:18 rivoplus bluetoothd[3313]: Endpoint registered: sender=:1.79 path=/MediaEndpoint/A2DPSink Jun 20 21:56:18 rivoplus pulseaudio[5998]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jun 20 21:56:18 rivoplus volumiobt[5939]: Agent registered Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# -e power on Jun 20 21:56:18 rivoplus volumiobt[5939]: Invalid command in menu main: -e Jun 20 21:56:18 rivoplus volumiobt[5939]: Jun 20 21:56:18 rivoplus volumiobt[5939]: Use "help" for a list of available commands in a menu. Jun 20 21:56:18 rivoplus volumiobt[5939]: Use "menu " if you want to enter any submenu. Jun 20 21:56:18 rivoplus volumiobt[5939]: Use "back" if you want to return to menu main. Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# agent on Jun 20 21:56:18 rivoplus volumiobt[5939]: Agent is already registered Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# discoverable on Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# pairable on Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# agent NoInputNoOutput Jun 20 21:56:18 rivoplus volumiobt[5939]: Agent is already registered Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# default-agent Jun 20 21:56:18 rivoplus volumiobt[5939]: [bluetooth]# quit Jun 20 21:56:18 rivoplus volumiobt[5939]: [59B blob data] Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 21:56:18 rivoplus volumio[3414]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 20 21:56:18 rivoplus volumio[3414]: info: Received Get System Version Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 21:56:18 rivoplus volumio[3414]: info: Received Get System Info Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:56:18 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 21:56:18 rivoplus volumio[3414]: info: Discovery: Getting this device information Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jun 20 21:56:18 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 21:56:19 rivoplus volumio[3414]: info: Disabling MyMusic plugin upnp Jun 20 21:56:19 rivoplus sudo[6004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 20 21:56:19 rivoplus sudo[6004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 21:56:19 rivoplus volumio[3414]: error: Upnp client error: Error: This socket has been ended by the other party Jun 20 21:56:19 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 20 21:56:19 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:56:19 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 21:56:20 rivoplus volumio[3414]: info: Enabling MyMusic plugin upnp Jun 20 21:56:20 rivoplus volumio[3414]: info: Enabling plugin upnp Jun 20 21:56:20 rivoplus volumio[3414]: info: Loading plugin "upnp"... Jun 20 21:56:20 rivoplus volumio[3414]: info: [1750424180973] Starting Upmpd Daemon Jun 20 21:56:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 21:56:20 rivoplus volumio[3414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 21:56:20 rivoplus volumio[3414]: Error: listen EADDRINUSE: address already in use :::6599 Jun 20 21:56:20 rivoplus volumio[3414]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jun 20 21:56:20 rivoplus volumio[3414]: at listenInCluster (net.js:1379:12) Jun 20 21:56:20 rivoplus volumio[3414]: at Server.listen (net.js:1465:7) Jun 20 21:56:20 rivoplus volumio[3414]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 20 21:56:20 rivoplus volumio[3414]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 20 21:56:20 rivoplus volumio[3414]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 20 21:56:20 rivoplus volumio[3414]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 20 21:56:20 rivoplus volumio[3414]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jun 20 21:56:20 rivoplus volumio[3414]: code: 'EADDRINUSE', Jun 20 21:56:20 rivoplus volumio[3414]: errno: -98, Jun 20 21:56:20 rivoplus volumio[3414]: syscall: 'listen', Jun 20 21:56:20 rivoplus volumio[3414]: address: '::', Jun 20 21:56:20 rivoplus volumio[3414]: port: 6599 Jun 20 21:56:20 rivoplus volumio[3414]: } Jun 20 21:56:21 rivoplus volumio[3414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 21:56:21 rivoplus sudo[6018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-20 21:55 Jun 20 21:56:21 rivoplus sudo[6018]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"