-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Sun 2024-04-14 13:34:58 EDT. -- Apr 14 13:33:00 volumio sudo[8988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 13:33:00 volumio sudo[8988]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:33:00 volumio sudo[8988]: pam_unix(sudo:session): session closed for user root Apr 14 13:33:00 volumio sudo[8990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 13:33:00 volumio sudo[8990]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:33:00 volumio sudo[8990]: pam_unix(sudo:session): session closed for user root Apr 14 13:33:00 volumio volumio[3409]: verbose: New Socket.io Connection to 192.168.2.13 from 192.168.2.145 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Apr 14 13:33:01 volumio sudo[8998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 13:33:01 volumio sudo[8998]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:33:01 volumio sudo[8998]: pam_unix(sudo:session): session closed for user root Apr 14 13:33:01 volumio sudo[9000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 13:33:01 volumio sudo[9000]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:33:01 volumio sudo[9000]: pam_unix(sudo:session): session closed for user root Apr 14 13:33:01 volumio volumio[3409]: verbose: New Socket.io Connection to 192.168.2.13 from 192.168.2.145 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6 Apr 14 13:33:01 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:02 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 13:33:02 volumio volumio[3409]: info: Received Get System Info Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:33:02 volumio volumio[3409]: info: Discovery: Getting this device information Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:02 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:02 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:02 volumio volumio[3409]: info: Listing playlists Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 13:33:02 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:02 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:33:03 volumio volumio[3409]: info: Received Get System Info Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:33:03 volumio volumio[3409]: info: Discovery: Getting this device information Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:03 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:33:03 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 13:33:04 volumio volumio[3409]: info: CoreCommandRouter::volumioGetQueue Apr 14 13:33:04 volumio volumio[3409]: info: CoreStateMachine::getQueue Apr 14 13:33:04 volumio volumio[3409]: info: CorePlayQueue::getQueue Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:06 volumio volumio[3409]: info: CURURI: music-library Apr 14 13:33:06 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:33:06 volumio volumio[3409]: info: Received Get System Info Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:33:06 volumio volumio[3409]: info: Discovery: Getting this device information Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:06 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:06 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:33:07 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:07 volumio volumio[3409]: info: CURURI: music-library/NAS Apr 14 13:33:07 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:08 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:08 volumio volumio[3409]: info: CURURI: music-library/NAS/Music Apr 14 13:33:08 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 13:33:21 volumio volumio[3409]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 13:33:21 volumio volumio[3409]: info: Received Get System Version Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 13:33:21 volumio volumio[3409]: info: Received Get System Info Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:33:21 volumio volumio[3409]: info: Discovery: Getting this device information Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::volumioGetState Apr 14 13:33:21 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:33:21 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:33:23 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:23 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz Apr 14 13:33:23 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:23 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:23 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz Apr 14 13:33:23 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:27 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:27 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac Apr 14 13:33:27 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:33:27 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac Apr 14 13:33:27 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac Apr 14 13:33:27 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2F01-_Autumn_rain.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%2010.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%202.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%203.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%204.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%205.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%206.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%207.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%208.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac Apr 14 13:33:27 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac in service mpd Apr 14 13:33:27 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAhmad%20Jamal%20Quartet%20Paris%202012%20flac%2Ftrack%209.flac&metadata=false Apr 14 13:33:27 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac Apr 14 13:33:31 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:31 volumio volumio[3409]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::ClearQueue Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::clearPlayQueue Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:33:31 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::addQueueItems Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::addQueueItems Apr 14 13:33:31 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/01-_Autumn_rain.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 2.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 3.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 4.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 5.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 6.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 7.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 8.flac Apr 14 13:33:31 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac Apr 14 13:33:31 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 9.flac Apr 14 13:33:31 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:33:31 volumio volumio[3409]: info: CoreCommandRouter::volumioPlay Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::play index 1 Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::play index undefined Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:31 volumio volumio[3409]: info: CoreStateMachine::startPlaybackTimer Apr 14 13:33:31 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand stop took 47 milliseconds Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand clear took 172 milliseconds Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac" Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio volumio[3409]: error: updateQueue error: null Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 87ms Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand add "NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac" took 80 milliseconds Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 75ms Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 73ms Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand play Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:33:31 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:33:31 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 44ms Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand play took 37 milliseconds Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 37ms Apr 14 13:33:31 volumio volumio[3409]: info: ------------------------------ 34ms Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand status took 202 milliseconds Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand status took 199 milliseconds Apr 14 13:33:31 volumio volumio[3409]: info: sendMpdCommand status took 197 milliseconds Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:31 volumio volumio[3409]: info: Apr 14 13:33:31 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:33:31 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:33:31 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand status took 56 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 45 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand status took 37 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand status took 33 milliseconds Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:33:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:33:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 355ms Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 395ms Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 392ms Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 144 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 136 milliseconds Apr 14 13:33:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 136 milliseconds Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1093,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1083 Kbps","isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:33:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1093,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1083 Kbps","isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:33:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1093,"duration":206,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1083 Kbps","isStreaming":false,"title":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:33:32 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:33:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:33:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:33:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 336ms Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 312ms Apr 14 13:33:32 volumio volumio[3409]: info: ------------------------------ 309ms Apr 14 13:33:39 volumio nmbd[540]: [2024/04/14 13:33:39.262784, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 14 13:33:39 volumio nmbd[540]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.13 for name WORKGROUP<1d>. Apr 14 13:33:39 volumio nmbd[540]: This response was from IP 192.168.2.111, reporting an IP address of 192.168.2.111. Apr 14 13:34:19 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:34:19 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz Apr 14 13:34:19 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:34:23 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k] Apr 14 13:34:23 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/1 - You're My Everything.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/3 - Play It Now.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/4 - Irresistable You.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/5 - Georgia On My Mind.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/6 - It's Sand, Man.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/1 - You're My Everything.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F1%20-%20You're%20My%20Everything.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/1 - You're My Everything.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::ClearQueue Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::stPlaybackTimer Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::serviceStop Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::serviceStop Apr 14 13:34:23 volumio volumio[3409]: info: ControllerMpd::stop Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::clearPlayQueue Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::addQueueItems Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::addQueueItems Apr 14 13:34:23 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/1 - You're My Everything.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/1 - You're My Everything.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/3 - Play It Now.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/3 - Play It Now.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/4 - Irresistable You.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/4 - Irresistable You.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/5 - Georgia On My Mind.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/5 - Georgia On My Mind.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/6 - It's Sand, Man.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/6 - It's Sand, Man.mp3 in service mpd Apr 14 13:34:23 volumio volumio[3409]: info: Apr 14 13:34:23 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F2%20-%20Lover.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F3%20-%20Play%20It%20Now.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/3 - Play It Now.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F4%20-%20Irresistable%20You.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/4 - Irresistable You.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F5%20-%20Georgia%20On%20My%20Mind.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/5 - Georgia On My Mind.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Al%20Cohn/Play%20It%20Now/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FAl%20Cohn-0000-Play%20It%20Now%20%5B64k%5D%2F6%20-%20It's%20Sand%2C%20Man.mp3&metadata=false Apr 14 13:34:23 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/6 - It's Sand, Man.mp3 Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand stop took 60 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPlay Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::play index 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::play index undefined Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::startPlaybackTimer Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3 Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:23 volumio volumio[3409]: info: Apr 14 13:34:23 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:23 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:23 volumio volumio[3409]: info: Apr 14 13:34:23 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:23 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand status took 33 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand stop took 11 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand status took 9 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand status took 7 milliseconds Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:23 volumio volumio[3409]: info: Apr 14 13:34:23 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:23 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand clear took 12 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 14 13:34:23 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3" Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:23 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:23 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: 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":"track 10.flac","artist":null,"album":null,"uri":"NAS/Music/Jazz/Ahmad Jamal Quartet Paris 2012 flac/track 10.flac","trackType":"flac"} Apr 14 13:34:23 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::syncState stateService stop Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:23 volumio volumio[3409]: info: No code Apr 14 13:34:23 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:23 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:23 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:23 volumio volumio[3409]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 13:34:23 volumio volumio[3409]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 13:34:23 volumio volumio[3409]: info: ------------------------------ 162ms Apr 14 13:34:23 volumio volumio[3409]: info: ------------------------------ 131ms Apr 14 13:34:23 volumio volumio[3409]: info: ------------------------------ 128ms Apr 14 13:34:23 volumio volumio[3409]: info: Apr 14 13:34:23 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:23 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:24 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:24 volumio volumio[3409]: error: updateQueue error: null Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:24 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 119ms Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand add "NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3" took 114 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 12ms Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 9ms Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand play Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:24 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:24 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 46ms Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand play took 42 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 42ms Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 39ms Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 10 milliseconds Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 20 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 18 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 10 milliseconds Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 51ms Apr 14 13:34:24 volumio volumio[3409]: info: Apr 14 13:34:24 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 33 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand status took 9 milliseconds Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:24 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":571,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:24 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":662,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:24 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 241ms Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 240ms Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 238ms Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 199 milliseconds Apr 14 13:34:24 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 196 milliseconds Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:24 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":587,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:24 volumio volumio[3409]: verbose: CURRENT POSITION 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:24 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:24 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:24 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 325ms Apr 14 13:34:24 volumio volumio[3409]: info: ------------------------------ 298ms Apr 14 13:34:31 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:34:31 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/01-Intro.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/02-Supply Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/03-Tonearm.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/04-Record Head.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/06-Take-up Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/07-Microgrooves.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/08-Three Minutes.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/01-Intro.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F01-Intro.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/01-Intro.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::ClearQueue Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::stPlaybackTimer Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::serviceStop Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 1 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::serviceStop Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::stop Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::clearPlayQueue Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::addQueueItems Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::addQueueItems Apr 14 13:34:32 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/01-Intro.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/01-Intro.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/02-Supply Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/02-Supply Reel.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/03-Tonearm.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/03-Tonearm.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/04-Record Head.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/04-Record Head.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/06-Take-up Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/06-Take-up Reel.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/07-Microgrooves.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/07-Microgrooves.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/08-Three Minutes.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/08-Three Minutes.mp3 in service mpd Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F02-Supply%20Reel.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/02-Supply Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F03-Tonearm.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/03-Tonearm.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F04-Record%20Head.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/04-Record Head.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F05-Stylus.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F06-Take-up%20Reel.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/06-Take-up Reel.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F07-Microgrooves.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/07-Microgrooves.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.R.O./AnalogPeopleInADigitalWorld/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FB.R.O.-Analog%20People%20In%20A%20Digital%20World%20mp3%2F08-Three%20Minutes.mp3&metadata=false Apr 14 13:34:32 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/08-Three Minutes.mp3 Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand stop took 61 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPlay Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::play index 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::play index undefined Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::startPlaybackTimer Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3 Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 40 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand stop took 12 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 9 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 7 milliseconds Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand clear took 9 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3" Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: 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":"Lover","artist":"Al Cohn","album":"Play It Now","uri":"NAS/Music/Jazz/Al Cohn-0000-Play It Now [64k]/2 - Lover.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService stop Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: No code Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 13:34:32 volumio volumio[3409]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 102ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 61ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 58ms Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: error: updateQueue error: null Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 85ms Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand add "NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3" took 80 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 13ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 10ms Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand play Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:32 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 23ms Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand play took 18 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 17ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 13ms Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 13 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 11 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 8 milliseconds Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: Apr 14 13:34:32 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 21 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 11 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand status took 8 milliseconds Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 174ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 190ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 188ms Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 160 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 157 milliseconds Apr 14 13:34:32 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 157 milliseconds Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":342,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Stylus","artist":"B.R.O.","album":"AnalogPeopleInADigitalWorld","uri":"NAS/Music/Jazz/B.R.O.-Analog People In A Digital World mp3/05-Stylus.mp3","trackType":"mp3"} Apr 14 13:34:32 volumio volumio[3409]: verbose: CURRENT POSITION 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:32 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:32 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:32 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 315ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 315ms Apr 14 13:34:32 volumio volumio[3409]: info: ------------------------------ 323ms Apr 14 13:34:37 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:34:37 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014 Apr 14 13:34:37 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/02. Can't Leave The Night.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/03. Confessions (Feat. Leland Whitty).mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/04. Kaleidoscope.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/05. Eyes Closed.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/06. Hedron.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/07. Differently, Still.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/08. Since You Asked Kindly.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/09. CS60.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/10. Sustain (Bonus Track).mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/00. BADBADNOTGOOD • III [2014] VØ.m3u Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F01.%20Triangle.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/02. Can't Leave The Night.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F02.%20Can't%20Leave%20The%20Night.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/02. Can't Leave The Night.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/03. Confessions (Feat. Leland Whitty).mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F03.%20Confessions%20(Feat.%20Leland%20Whitty).mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/03. Confessions (Feat. Leland Whitty).mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/04. Kaleidoscope.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F04.%20Kaleidoscope.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/04. Kaleidoscope.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/05. Eyes Closed.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F05.%20Eyes%20Closed.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/05. Eyes Closed.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/06. Hedron.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F06.%20Hedron.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/06. Hedron.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/07. Differently, Still.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F07.%20Differently%2C%20Still.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/07. Differently, Still.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/08. Since You Asked Kindly.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F08.%20Since%20You%20Asked%20Kindly.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/08. Since You Asked Kindly.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/09. CS60.mp3 in service mpd Apr 14 13:34:37 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F09.%20CS60.mp3&metadata=false Apr 14 13:34:37 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/09. CS60.mp3 Apr 14 13:34:37 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/10. Sustain (Bonus Track).mp3 in service mpd Apr 14 13:34:38 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=BADBADNOTGOOD/III/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FJazz%2FBADBADNOTGOOD-III-2014%2F10.%20Sustain%20(Bonus%20Track).mp3&metadata=false Apr 14 13:34:38 volumio volumio[3409]: info: URI /mnt/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/10. Sustain (Bonus Track).mp3 Apr 14 13:34:38 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/00. BADBADNOTGOOD • III [2014] VØ.m3u in service mpd Apr 14 13:34:39 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::ClearQueue Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::stPlaybackTimer Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::serviceStop Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 4 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::serviceStop Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::stop Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::clearPlayQueue Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::addQueueItems Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::addQueueItems Apr 14 13:34:39 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/02. Can't Leave The Night.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/02. Can't Leave The Night.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/03. Confessions (Feat. Leland Whitty).mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/03. Confessions (Feat. Leland Whitty).mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/04. Kaleidoscope.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/04. Kaleidoscope.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/05. Eyes Closed.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/05. Eyes Closed.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/06. Hedron.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/06. Hedron.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/07. Differently, Still.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/07. Differently, Still.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/08. Since You Asked Kindly.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/08. Since You Asked Kindly.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/09. CS60.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/09. CS60.mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/10. Sustain (Bonus Track).mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/10. Sustain (Bonus Track).mp3 Apr 14 13:34:39 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/00. BADBADNOTGOOD • III [2014] VØ.m3u Apr 14 13:34:39 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/Jazz/BADBADNOTGOOD-III-2014/00. BADBADNOTGOOD • III [2014] VØ.m3u Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPlay Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::play index 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::play index undefined Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::startPlaybackTimer Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3 Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand stop took 54 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand stop took 14 milliseconds Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 23 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand clear took 22 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 20 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 18 milliseconds Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3" Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: 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} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: No code Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: 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} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: No code Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 87ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 87ms Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: error: updateQueue error: null Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 85ms Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 80 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand add "NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3" took 79 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 13ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 12ms Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand play Apr 14 13:34:39 volumio volumio[3409]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 116ms Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces system playlist update Apr 14 13:34:39 volumio volumio[3409]: info: Ignoring MPD Status Update Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 43ms Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand play took 37 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 34ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 32ms Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 12 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 9 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 7 milliseconds Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: Apr 14 13:34:39 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::getState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 20 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 14 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand status took 13 milliseconds Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseState Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:39 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:39 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 156ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 172ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 170ms Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 130 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 116 milliseconds Apr 14 13:34:39 volumio volumio[3409]: info: sendMpdCommand playlistinfo took 113 milliseconds Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:39 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:39 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ControllerMpd::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::servicePushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Triangle","artist":"BADBADNOTGOOD","album":"III","uri":"NAS/Music/Jazz/BADBADNOTGOOD-III-2014/01. Triangle.mp3","trackType":"mp3"} Apr 14 13:34:39 volumio volumio[3409]: verbose: CURRENT POSITION 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState stateService play Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:34:39 volumio volumio[3409]: info: Received an update from plugin. extracting info from payload Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:39 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:39 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 243ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 232ms Apr 14 13:34:39 volumio volumio[3409]: info: ------------------------------ 229ms Apr 14 13:34:53 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 13:34:53 volumio volumio[3409]: info: CURURI: music-library/NAS/Music/00-FLAC-192-OLNY Apr 14 13:34:53 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:53 volumio volumio[3409]: info: Preloading song: music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:53 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac in service mpd Apr 14 13:34:53 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac in service mpd Apr 14 13:34:53 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Prince%20And%20The%20Revolution/Parade/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2FPrince%20And%20The%20Revolution%20-%20Parade.flac&metadata=false Apr 14 13:34:53 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:53 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=Prince%20And%20The%20Revolution/Parade/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2FPrince%20And%20The%20Revolution%20-%20Parade.flac&metadata=false Apr 14 13:34:53 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:56 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:56 volumio volumio[3409]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::ClearQueue Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::stop Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::stPlaybackTimer Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::updateTrackBlock Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::getTrackBlock Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::pushState Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:56 volumio volumio[3409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:34:56 volumio volumio[3409]: info: CoreCommandRouter::volumioPushState Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::serviceStop Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::getTrack 0 Apr 14 13:34:56 volumio volumio[3409]: info: CoreCommandRouter::serviceStop Apr 14 13:34:56 volumio volumio[3409]: info: ControllerMpd::stop Apr 14 13:34:56 volumio volumio[3409]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::clearPlayQueue Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::saveQueue Apr 14 13:34:56 volumio volumio[3409]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:34:56 volumio volumio[3409]: info: CoreStateMachine::addQueueItems Apr 14 13:34:56 volumio volumio[3409]: info: CorePlayQueue::addQueueItems Apr 14 13:34:56 volumio volumio[3409]: info: Preload queue cleared Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:56 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:56 volumio volumio[3409]: info: Using cached record of: music-library/NAS/Music/00-FLAC-192-OLNY/Prince And The Revolution - Parade.flac Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192] Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192] in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Depeche Mode Black Celebration Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Depeche Mode Black Celebration in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Dire Straits - Dire Straits (1978) (24_192) Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Dire Straits - Dire Straits (1978) (24_192) in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Doors L.A. Woman Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Doors L.A. Woman in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Emil Gilels - Beethoven - Piano Sonata No. 8 and No. 14 Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Emil Gilels - Beethoven - Piano Sonata No. 8 and No. 14 in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Herbert Von Karajan - Maurice Ravel-Bolero, Claude Debussy-La Mer (1965) Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Herbert Von Karajan - Maurice Ravel-Bolero, Claude Debussy-La Mer (1965) in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/IngridMichaelson-LightsOut(Vinyl) Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/IngridMichaelson-LightsOut(Vinyl) in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Joe Cocker 9 LPs [Vinyl 24-192] (1968-1991) Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Joe Cocker 9 LPs [Vinyl 24-192] (1968-1991) in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Louis Armstrong - 2012 - What a Wonderful World [24bit-192kHz] [FLAC] Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Louis Armstrong - 2012 - What a Wonderful World [24bit-192kHz] [FLAC] in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Michael Jackson - HIStory (1995) [Vinyl] (24bit-192kHz) Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Michael Jackson - HIStory (1995) [Vinyl] (24bit-192kHz) in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Miles Davis - Bitches Brew (1969) 2010 reissue 24-192 Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Miles Davis - Bitches Brew (1969) 2010 reissue 24-192 in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Mozart - Symphonie No.40 & No.41 Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Mozart - Symphonie No.40 & No.41 in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Radiohead-OK Computer (1997) 24-192 Vinyl FLAC Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Radiohead-OK Computer (1997) 24-192 Vinyl FLAC in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Salomone Rossi - Il Mantovano Hebreo-Profeti Della Quinta 24.192 Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Salomone Rossi - Il Mantovano Hebreo-Profeti Della Quinta 24.192 in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Adding Item to queue: music-library/NAS/Music/00-FLAC-192-OLNY/Yes - Yes (1969) [192-24] Apr 14 13:34:56 volumio volumio[3409]: info: Exploding uri music-library/NAS/Music/00-FLAC-192-OLNY/Yes - Yes (1969) [192-24] in service mpd Apr 14 13:34:56 volumio volumio[3409]: info: Apr 14 13:34:56 volumio volumio[3409]: ---------------------------- MPD announces state update: player Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F01.%20Serve%20The%20Servants.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/01. Serve The Servants.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F02.%20Scentless%20Apprentice.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/02. Scentless Apprentice.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F03.%20Heart-Shaped%20Box.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/03. Heart-Shaped Box.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F04.%20Rape%20Me.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/04. Rape Me.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F05.%20Frances%20Farmer%20Will%20Have%20Her%20Revenge%20On%20Seattle.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/05. Frances Farmer Will Have Her Revenge On Seattle.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F06.%20Dumb.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/06. Dumb.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F07.%20Very%20Ape.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/07. Very Ape.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F08.%20Milk%20It.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/08. Milk It.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F09.%20Pennyroyal%20Tea.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/09. Pennyroyal Tea.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F10.%20Radio%20Friendly%20Unit%20Shifter.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/10. Radio Friendly Unit Shifter.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F11.%20Tourette's.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/11. Tourette's.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=NIRVANA/In%20Utero/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F(1993)%20Nirvana%20-%20In%20Utero%20(FLAC)%20%5B24-192%5D%2F12.%20All%20Apologies.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/(1993) Nirvana - In Utero (FLAC) [24-192]/12. All Apologies.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FA1%20-%20You%20Done%20Lost%20Your%20Good%20Thing%20Now.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/A1 - You Done Lost Your Good Thing Now.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FA2%20-%20Mr%20Pawnbroker.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/A2 - Mr Pawnbroker.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FA3%20-%20Understand.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/A3 - Understand.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FA4%20-%20Someday%20Baby.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/A4 - Someday Baby.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FA5%20-%20Driving%20Wheel.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/A5 - Driving Wheel.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FB1%20-%20Walking%20Dr%20Bill.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/B1 - Walking Dr Bill.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FB2%20-%20My%20Own%20Fault.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/B2 - My Own Fault.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FB3%20-%20Fishin'%20After%20Me.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/B3 - Fishin' After Me.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FB4%20-%20Hold%20That%20Train.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/B4 - Hold That Train.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FB5%20-%20Please%20Set%20a%20Date.flac&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/B5 - Please Set a Date.flac Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FBB%20King%20-%20My%20Kind%20of%20Blues.cue&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/BB King - My Kind of Blues.cue Apr 14 13:34:57 volumio volumio[3409]: info: ALBUMART /albumart?cacheid=11&web=B.B.%20King/My%20Kind%20of%20Blues/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F00-FLAC-192-OLNY%2F1961%20-%20B.B.%20King%20-%20My%20Kind%20Of%20Blues%2FBB%20King%20-%20My%20Kind%20of%20Blues.cue&metadata=false Apr 14 13:34:57 volumio volumio[3409]: info: URI /mnt/NAS/Music/00-FLAC-192-OLNY/1961 - B.B. King - My Kind Of Blues/BB King - My Kind of Blues.cue Apr 14 13:34:57 volumio volumio[3409]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 13:34:57 volumio volumio[3409]: Error: Unable to resolve or reject the same promise twice Apr 14 13:34:57 volumio volumio[3409]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 13:34:57 volumio volumio[3409]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 14 13:34:57 volumio volumio[3409]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 13:34:57 volumio volumio[3409]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 13:34:57 volumio volumio[3409]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 13:34:57 volumio volumio[3409]: at Socket.emit (events.js:315:20) Apr 14 13:34:57 volumio volumio[3409]: at addChunk (internal/streams/readable.js:309:12) Apr 14 13:34:57 volumio volumio[3409]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 14 13:34:57 volumio volumio[3409]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 14 13:34:57 volumio volumio[3409]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 13:34:57 volumio volumio[3409]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 13:34:58 volumio sudo[9106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 13:33 Apr 14 13:34:58 volumio sudo[9106]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"