-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Fri 2024-11-29 12:47:44 EST. -- Nov 29 12:46:00 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:46:01 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:02 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:46:02 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:05 rivergatesongs sudo[29736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 12:46:05 rivergatesongs sudo[29736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:46:05 rivergatesongs sudo[29736]: pam_unix(sudo:session): session closed for user root Nov 29 12:46:05 rivergatesongs sudo[29739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 12:46:05 rivergatesongs sudo[29739]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:46:05 rivergatesongs sudo[29739]: pam_unix(sudo:session): session closed for user root Nov 29 12:46:05 rivergatesongs volumio[994]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.30 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 29 12:46:05 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 12:46:05 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:06 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:06 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:06 rivergatesongs volumio[994]: info: Listing playlists Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 12:46:06 rivergatesongs volumio[994]: info: Received Get System Info Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:46:06 rivergatesongs volumio[994]: info: Discovery: Getting this device information Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:06 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:06 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 12:46:06 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:46:07 rivergatesongs volumio[994]: info: Received Get System Info Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:46:07 rivergatesongs volumio[994]: info: Discovery: Getting this device information Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:07 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:07 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:46:08 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:46:08 rivergatesongs volumio[994]: info: Received Get System Info Nov 29 12:46:08 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:46:08 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:46:08 rivergatesongs volumio[994]: info: Discovery: Getting this device information Nov 29 12:46:08 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:08 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:08 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:46:12 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:46:12 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:12 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:13 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:15 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:46:15 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Preloading song: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 in service upnp_browser Nov 29 12:46:16 rivergatesongs volumio[994]: info: Exploding uri upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 in service upnp_browser Nov 29 12:46:19 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::ClearQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::updateTrackBlock Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrackBlock Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::stPlaybackTimer Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::serviceStop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::serviceStop Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::stop Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::clearPlayQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::addQueueItems Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::addQueueItems Nov 29 12:46:19 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::updateTrackBlock Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrackBlock Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPlay Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::play index 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::play index undefined Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: Nov 29 12:46:19 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand stop took 18 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:19 rivergatesongs volumio[994]: info: Nov 29 12:46:19 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:19 rivergatesongs volumio[994]: info: Nov 29 12:46:19 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand status took 4 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand status took 3 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand status took 2 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:19 rivergatesongs volumio[994]: error: Upnp client error: Error: This socket has been ended by the other party Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Man","artist":"Billy Joel","album":"The Essential Billy Joel","uri":"USB/Samsung_USB/Billy Joel - The Essential Billy Joel (2013) [24-96 HD FLAC]/01-01 Piano Man.flac","trackType":"flac"} Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: No code Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Man","artist":"Billy Joel","album":"The Essential Billy Joel","uri":"USB/Samsung_USB/Billy Joel - The Essential Billy Joel (2013) [24-96 HD FLAC]/01-01 Piano Man.flac","trackType":"flac"} Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: No code Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Man","artist":"Billy Joel","album":"The Essential Billy Joel","uri":"USB/Samsung_USB/Billy Joel - The Essential Billy Joel (2013) [24-96 HD FLAC]/01-01 Piano Man.flac","trackType":"flac"} Nov 29 12:46:19 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus stop Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: No code Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:19 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:19 rivergatesongs volumio[994]: info: ------------------------------ 36ms Nov 29 12:46:19 rivergatesongs volumio[994]: info: ------------------------------ 34ms Nov 29 12:46:19 rivergatesongs volumio[994]: info: ------------------------------ 34ms Nov 29 12:46:21 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::ClearQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::clearPlayQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::addQueueItems Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::addQueueItems Nov 29 12:46:21 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8095 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8096 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8097 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8098 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8099 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8100 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8101 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8102 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8103 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8104 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8105 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8106 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8107 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8108 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8109 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8110 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8111 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 Nov 29 12:46:21 rivergatesongs volumio[994]: info: Using cached record of: upnp/http://192.168.1.31:50001/ContentDirectory/control@28$8095$@8112 Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::updateTrackBlock Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::getTrackBlock Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPlay Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::play index 0 Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::play index undefined Nov 29 12:46:21 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:21 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 12:46:24 rivergatesongs volumio[994]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 29 12:46:24 rivergatesongs volumio[994]: info: Received Get System Version Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:46:24 rivergatesongs volumio[994]: info: Received Get System Info Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:46:24 rivergatesongs volumio[994]: info: Discovery: Getting this device information Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetState Nov 29 12:46:24 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:24 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:46:35 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPlay Nov 29 12:46:35 rivergatesongs volumio[994]: info: CoreStateMachine::play index undefined Nov 29 12:46:35 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:35 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:39 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreStateMachine::ClearQueue Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:39 rivergatesongs volumio[994]: info: CorePlayQueue::clearPlayQueue Nov 29 12:46:39 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:39 rivergatesongs volumio[994]: info: CoreStateMachine::addQueueItems Nov 29 12:46:39 rivergatesongs volumio[994]: info: CorePlayQueue::addQueueItems Nov 29 12:46:39 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:46:39 rivergatesongs volumio[994]: info: Adding Item to queue: upnp/folder/http://192.168.1.31:50001/ContentDirectory/control@28$8095 Nov 29 12:46:39 rivergatesongs volumio[994]: info: Exploding uri upnp/folder/http://192.168.1.31:50001/ContentDirectory/control@28$8095 in service upnp_browser Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::saveQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::updateTrackBlock Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrackBlock Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPlay Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::play index 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::stop Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::play index undefined Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::startPlaybackTimer Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: [1732902400021] ControllerUPNPBrowser::clearAddPlayTrack Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand stop took 1 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand clear took 2 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.31:50002/m/NDLNA/8095.flac" Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: error: updateQueue error: null Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 4ms Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.31:50002/m/NDLNA/8095.flac" Nov 29 12:46:40 rivergatesongs volumio[994]: error: updateQueue error: null Nov 29 12:46:40 rivergatesongs volumio[994]: error: updateQueue error: null Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 25ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 24ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand add "http://192.168.1.31:50002/m/NDLNA/8095.flac" took 2 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 5ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand play took 4 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 3ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 2ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 29 12:46:40 rivergatesongs kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 11 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 13 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 11 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8095.flac","artist":null,"album":null,"uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus stop Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 17ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 8 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 2 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8095.flac","artist":null,"album":null,"uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8095.flac","artist":null,"album":null,"uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 38ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 37ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 21 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 43ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 37ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces system playlist update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Ignoring MPD Status Update Nov 29 12:46:40 rivergatesongs volumio[994]: info: Nov 29 12:46:40 rivergatesongs volumio[994]: ---------------------------- MPD announces state update: player Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::getState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand status Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 33ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 32 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 17ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 16 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 17ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand status took 15 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseState Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 63ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: ControllerMpd::parseTrackInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ControllerMpd::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::servicePushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getTrack 0 Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"The Best Of","uri":"http://192.168.1.31:50002/m/NDLNA/8095.flac","trackType":"flac"} Nov 29 12:46:40 rivergatesongs volumio[994]: verbose: CURRENT POSITION 0 Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState stateService play Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::syncState currentStatus play Nov 29 12:46:40 rivergatesongs volumio[994]: info: Received an update from plugin. extracting info from payload Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::pushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioPushState Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 60ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 45ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: ------------------------------ 43ms Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CoreStateMachine::getQueue Nov 29 12:46:40 rivergatesongs volumio[994]: info: CorePlayQueue::getQueue Nov 29 12:47:37 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:47:37 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:47:40 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:47:40 rivergatesongs volumio[994]: info: Preload queue cleared Nov 29 12:47:43 rivergatesongs volumio[994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 12:47:43 rivergatesongs volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:47:43 rivergatesongs volumio[994]: TypeError: Cannot read property 'length' of undefined Nov 29 12:47:43 rivergatesongs volumio[994]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 29 12:47:43 rivergatesongs volumio[994]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 29 12:47:43 rivergatesongs volumio[994]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 29 12:47:43 rivergatesongs volumio[994]: at Parser.emit (events.js:315:20) Nov 29 12:47:43 rivergatesongs volumio[994]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 29 12:47:43 rivergatesongs volumio[994]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 29 12:47:43 rivergatesongs volumio[994]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 29 12:47:43 rivergatesongs volumio[994]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 29 12:47:43 rivergatesongs volumio[994]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 29 12:47:43 rivergatesongs volumio[994]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 29 12:47:43 rivergatesongs volumio[994]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 29 12:47:43 rivergatesongs volumio[994]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 29 12:47:43 rivergatesongs volumio[994]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 29 12:47:43 rivergatesongs volumio[994]: at IncomingMessage.emit (events.js:327:22) Nov 29 12:47:43 rivergatesongs volumio[994]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 29 12:47:43 rivergatesongs volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 29 12:47:43 rivergatesongs volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:47:44 rivergatesongs sudo[29933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 12:46 Nov 29 12:47:44 rivergatesongs sudo[29933]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"