-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Sat 2025-03-01 15:23:30 AEDT. -- Mar 01 15:22:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:00 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:00 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:01 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:01 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:02 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:02 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:02 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.178:3000 from 192.168.1.105 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:02 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:02 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:03 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:03 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:03 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:03 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:04 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:04 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:05 volumio sudo[28772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 15:22:05 volumio sudo[28772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:05 volumio sudo[28772]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:05 volumio sudo[28777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 15:22:05 volumio sudo[28777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:05 volumio sudo[28777]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:05 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 15:22:05 volumio sudo[28785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 15:22:05 volumio sudo[28785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:05 volumio sudo[28785]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:05 volumio sudo[28788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 15:22:05 volumio sudo[28788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:05 volumio sudo[28788]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 15:22:05 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 01 15:22:05 volumio volumio[1133]: info: Received Get System Info Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:05 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:05 volumio volumio[1133]: info: Listing playlists Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:05 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:05 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:06 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:06 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 01 15:22:07 volumio volumio[1133]: info: Received Get System Info Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:07 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:07 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:07 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:07 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:07 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:08 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:08 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:09 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:09 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:09 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:09 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 01 15:22:10 volumio volumio[1133]: info: Received Get System Info Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:10 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:10 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:10 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:10 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 01 15:22:11 volumio volumio[1133]: info: Received Get System Info Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:11 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:11 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:11 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:12 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:12 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:13 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:13 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:13 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:13 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:14 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:14 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:15 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 01 15:22:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:15 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:15 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:16 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:16 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:18 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:18 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:19 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:19 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:20 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:20 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:22 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:22 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:23 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:23 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:24 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:24 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:25 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:25 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 01 15:22:25 volumio volumio[1133]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 01 15:22:25 volumio volumio[1133]: info: Received Get System Version Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 01 15:22:25 volumio volumio[1133]: info: Received Get System Info Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:25 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:25 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:26 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:26 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:27 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:27 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:27 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:27 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:28 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:28 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:29 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:29 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:30 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:30 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:31 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:31 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:31 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:31 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:32 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:32 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:33 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:33 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:34 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:34 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:34 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:34 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F177 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F178 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F179 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F181 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F180 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F182 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F183 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F184 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F185 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F186 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F187 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F189 Mar 01 15:22:34 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F188 Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F177 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F178 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F179 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F181 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F180 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F182 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F183 in service upnp_browser Mar 01 15:22:34 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F184 in service upnp_browser Mar 01 15:22:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:35 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:35 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:35 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F185 in service upnp_browser Mar 01 15:22:35 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F186 in service upnp_browser Mar 01 15:22:35 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F187 in service upnp_browser Mar 01 15:22:35 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F189 in service upnp_browser Mar 01 15:22:35 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F188 in service upnp_browser Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:36 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::stop Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::serviceStop Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::serviceStop Mar 01 15:22:36 volumio volumio[1133]: info: Airplay Stop Mar 01 15:22:36 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::saveQueue Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::addQueueItems Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::addQueueItems Mar 01 15:22:36 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:36 volumio volumio[1133]: info: Adding Item to queue: upnp/folder/http://192.168.1.120:52100/ContentDirectory/control@5892 Mar 01 15:22:36 volumio volumio[1133]: info: Exploding uri upnp/folder/http://192.168.1.120:52100/ContentDirectory/control@5892 in service upnp_browser Mar 01 15:22:36 volumio sudo[29214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1613 Mar 01 15:22:36 volumio sudo[29214]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:36 volumio sudo[29214]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:36 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::saveQueue Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay Mar 01 15:22:36 volumio volumio[1133]: verbose: UNSET VOLATILE: Service: airplay_emulation Mar 01 15:22:36 volumio volumio[1133]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::play index 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::stop Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::play index undefined Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: info: [1740802956518] ControllerUPNPBrowser::clearAddPlayTrack Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand stop took 3 milliseconds Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand clear took 3 milliseconds Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.120:52100/Music/F177.m4a?Reader=368" Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 8ms Mar 01 15:22:36 volumio volumio[1133]: error: Upnp client error: Error: This socket has been ended by the other party Mar 01 15:22:36 volumio sudo[29219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1613 Mar 01 15:22:36 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:36 volumio sudo[29219]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:36 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.120:52100/Music/F177.m4a?Reader=368" Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 16ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 15ms Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand add "http://192.168.1.120:52100/Music/F177.m4a?Reader=368" took 3 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:36 volumio sudo[29219]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:36 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 7ms Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand play took 5 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 5ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 4ms Mar 01 15:22:36 volumio volumio[1133]: info: Shairport-Sync paused with USR2 Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 6 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 5 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: Mar 01 15:22:36 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 11 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 8 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand status took 6 milliseconds Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:36 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:36 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 36ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 42ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 41ms Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 01 15:22:36 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:36 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:36 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:36 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F177.m4a?Reader=368","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F177.m4a?Reader=368","trackType":""} Mar 01 15:22:36 volumio volumio[1133]: verbose: CURRENT POSITION 0 Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:36 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:36 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 73ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 67ms Mar 01 15:22:36 volumio volumio[1133]: info: ------------------------------ 66ms Mar 01 15:22:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:37 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:37 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:37 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Mar 01 15:22:37 volumio sudo[29252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 15:22:37 volumio sudo[29252]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 15:22:37 volumio shairport-sync[1613]: {"time":1740802906740,"response":"stopAirplayPlayback Success"} Mar 01 15:22:37 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 15:22:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 15:22:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 15:22:37 volumio sudo[29252]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:37 volumio volumio[1133]: info: Restarting Shairport-Sync after stop Mar 01 15:22:37 volumio volumio[1133]: info: Shairport-Sync retarted Mar 01 15:22:37 volumio sudo[29272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 15:22:37 volumio sudo[29272]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:22:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 15:22:37 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 15:22:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 15:22:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 15:22:37 volumio sudo[29272]: pam_unix(sudo:session): session closed for user root Mar 01 15:22:37 volumio volumio[1133]: info: Shairport-Sync retarted Mar 01 15:22:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:38 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:38 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:39 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:39 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:40 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:40 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:41 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:41 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:41 volumio volumio[1133]: info: VolumeController::SetAlsaVolume18 Mar 01 15:22:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:41 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:41 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:41 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:42 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:42 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:43 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:43 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:44 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:44 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:45 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::ClearQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::serviceStop Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::serviceStop Mar 01 15:22:45 volumio volumio[1133]: info: [1740802965761] ControllerUPNPBrowser::stop Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::saveQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::addQueueItems Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::addQueueItems Mar 01 15:22:45 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F177 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F177 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F178 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F178 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F179 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F179 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F181 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F181 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F180 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F180 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F182 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F182 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F183 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F183 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F184 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F184 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F185 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F185 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F186 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F186 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F187 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F187 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F189 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F189 Mar 01 15:22:45 volumio volumio[1133]: info: Adding Item to queue: upnp/http://192.168.1.120:52100/ContentDirectory/control@F188 Mar 01 15:22:45 volumio volumio[1133]: info: Using cached record of: upnp/http://192.168.1.120:52100/ContentDirectory/control@F188 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::saveQueue Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::play index 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::play index undefined Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: [1740802965774] ControllerUPNPBrowser::clearAddPlayTrack Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand stop took 17 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand stop took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand clear took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.120:52100/Music/F178.m4a?Reader=356" Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: No code Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: No code Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 21ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 20ms Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 22ms Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 27ms Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.120:52100/Music/F178.m4a?Reader=356" Mar 01 15:22:45 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:45 volumio volumio[1133]: error: updateQueue error: null Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 45ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 45ms Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand add "http://192.168.1.120:52100/Music/F178.m4a?Reader=356" took 5 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces system playlist update Mar 01 15:22:45 volumio volumio[1133]: info: Ignoring MPD Status Update Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 9ms Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand play took 5 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 3ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 3ms Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: Mar 01 15:22:45 volumio volumio[1133]: ---------------------------- MPD announces state update: player Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::getState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 6 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand status took 3 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseState Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:45 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:45 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 19ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 26ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 25ms Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 01 15:22:45 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:45 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:45 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ControllerMpd::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::servicePushState Mar 01 15:22:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 1 Mar 01 15:22:45 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F178.m4a?Reader=356","artist":null,"album":null,"uri":"http://192.168.1.120:52100/Music/F178.m4a?Reader=356","trackType":""} Mar 01 15:22:45 volumio volumio[1133]: verbose: CURRENT POSITION 1 Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:22:45 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:45 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 44ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 40ms Mar 01 15:22:45 volumio volumio[1133]: info: ------------------------------ 41ms Mar 01 15:22:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:46 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:46 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:47 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:47 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:47 volumio volumio[1133]: info: VolumeController::SetAlsaVolume+ Mar 01 15:22:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:47 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:47 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:47 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:48 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:48 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:49 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:49 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:49 volumio volumio[1133]: info: VolumeController::SetAlsaVolume+ Mar 01 15:22:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:49 volumio volumio[1133]: info: CoreStateMachine::pushState Mar 01 15:22:49 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:22:49 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState Mar 01 15:22:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:50 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:50 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:51 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:51 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:52 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:52 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:53 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:53 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:54 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:54 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:55 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:55 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:55 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:55 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:56 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:56 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:57 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:57 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:22:57 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:22:57 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:57 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:22:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:58 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:58 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:22:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:22:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:22:59 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:22:59 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:00 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:00 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:01 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:23:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:01 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:01 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:01 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:23:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:02 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:02 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:03 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:03 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:04 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:04 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:05 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:05 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:06 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:06 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:07 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:07 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:23:07 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F175 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F174 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F172 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F173 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F171 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F170 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F169 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F168 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F710 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F703 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F167 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F166 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F165 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F90 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F89 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F88 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F87 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F86 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F85 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F84 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F83 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F27 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F26 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F164 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F162 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F161 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F160 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F159 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F158 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F202 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F201 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F200 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F199 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F198 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F196 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F484 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F488 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F486 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F487 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F482 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F481 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F480 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F478 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F479 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F155 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F152 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F695 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F195 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F194 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F193 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F192 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F191 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F190 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F211 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F209 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F208 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F207 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F206 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F205 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F203 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F204 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F466 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F465 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F464 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F463 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F462 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F522 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F518 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F515 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F17 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F426 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F425 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F424 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F423 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F422 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F421 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F420 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F419 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F418 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F417 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F416 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F414 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F413 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F415 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F412 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F338 Mar 01 15:23:07 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F337 Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F175 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F174 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F172 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F173 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F171 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F170 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F169 in service upnp_browser Mar 01 15:23:07 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F168 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:08 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:08 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F710 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F703 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F167 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F166 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F165 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F90 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F89 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F88 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F87 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F86 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F85 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F84 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F83 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F27 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F26 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F164 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F162 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F161 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F160 in service upnp_browser Mar 01 15:23:08 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F159 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:09 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:09 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F158 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F202 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F201 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F200 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F199 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F198 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F196 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F484 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F488 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F486 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F487 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F482 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F481 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F480 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F478 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F479 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F155 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F152 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F695 in service upnp_browser Mar 01 15:23:09 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F195 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:10 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:10 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F194 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F193 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F192 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F191 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F190 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F211 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F209 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F208 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F207 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F206 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F205 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F203 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F204 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F466 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F465 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F464 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F463 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F462 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F522 in service upnp_browser Mar 01 15:23:10 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F518 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:11 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:11 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F515 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F17 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F426 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F425 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F424 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F423 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F422 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F421 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F420 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F419 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F418 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F417 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F416 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F414 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F413 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F415 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F412 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F338 in service upnp_browser Mar 01 15:23:11 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F337 in service upnp_browser Mar 01 15:23:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:12 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:12 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:13 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:13 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:14 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:14 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:15 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:15 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:16 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:16 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:17 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:17 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:18 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:18 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:19 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:19 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:20 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:20 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:21 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:21 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:22 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:22 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:23 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:23 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:24 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:24 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:23:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:25 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:25 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:25 volumio volumio[1133]: info: Preload queue cleared Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F427 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F45 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F50 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F583 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F430 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F41 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F218 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F562 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F698 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F42 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F658 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F745 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F2 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F13 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F43 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F44 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F46 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F47 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F48 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F49 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F51 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F59 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F63 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F135 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F137 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F220 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F271 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F272 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F312 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F428 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F429 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F431 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F432 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F469 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F576 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F790 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F792 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F9 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F11 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F399 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F433 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F490 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F573 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F602 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F659 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F661 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F696 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F763 Mar 01 15:23:25 volumio volumio[1133]: info: Preloading song: upnp/http://192.168.1.120:52100/ContentDirectory/control@F28 Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F427 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F45 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F50 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F583 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F430 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F41 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F218 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F562 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F698 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F42 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F658 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F745 in service upnp_browser Mar 01 15:23:25 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F2 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F13 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:26 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:26 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F43 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F44 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F46 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F47 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F48 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F49 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F51 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F59 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F63 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F135 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F137 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F220 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F271 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F272 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F312 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F428 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F429 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F431 in service upnp_browser Mar 01 15:23:26 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F432 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F469 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:27 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:27 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F576 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F790 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F792 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F9 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F11 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F399 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F433 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F490 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F573 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F602 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F659 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F661 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F696 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:23:27 volumio volumio[1133]: info: Discovery: Getting this device information Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F763 in service upnp_browser Mar 01 15:23:27 volumio volumio[1133]: info: Exploding uri upnp/http://192.168.1.120:52100/ContentDirectory/control@F28 in service upnp_browser Mar 01 15:23:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:28 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:28 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:29 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:29 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Mar 01 15:23:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:23:30 volumio volumio[1133]: info: CoreStateMachine::getQueue Mar 01 15:23:30 volumio volumio[1133]: info: CorePlayQueue::getQueue Mar 01 15:23:30 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 15:23:30 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:23:30 volumio volumio[1133]: TypeError: Cannot read property 'length' of undefined Mar 01 15:23:30 volumio volumio[1133]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 01 15:23:30 volumio volumio[1133]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 01 15:23:30 volumio volumio[1133]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 01 15:23:30 volumio volumio[1133]: at Parser.emit (events.js:315:20) Mar 01 15:23:30 volumio volumio[1133]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 01 15:23:30 volumio volumio[1133]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 01 15:23:30 volumio volumio[1133]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 01 15:23:30 volumio volumio[1133]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 01 15:23:30 volumio volumio[1133]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 01 15:23:30 volumio volumio[1133]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 01 15:23:30 volumio volumio[1133]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 01 15:23:30 volumio volumio[1133]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 01 15:23:30 volumio volumio[1133]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 01 15:23:30 volumio volumio[1133]: at IncomingMessage.emit (events.js:327:22) Mar 01 15:23:30 volumio volumio[1133]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 01 15:23:30 volumio volumio[1133]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 01 15:23:30 volumio volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:23:30 volumio sudo[30160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 15:22 Mar 01 15:23:30 volumio sudo[30160]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"