-- Logs begin at Sun 2026-08-30 21:21:35 MST, end at Mon 2026-08-31 21:00:10 MST. -- Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.616-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.50.143:47322 Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.630-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.586933ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.631-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.50.143:47322 @ 0x2cbea20" latency=631.686544ms timeout=20s Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.631-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 20:59:13 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:13 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:13 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.639-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" name=Private-Chamber Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.642-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" language=en Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.645-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" timezone=America/Phoenix Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.646-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" available=true connected=true macAddress=e4:5f:01:41:5f:1b ip4Address=192.168.50.24/24 ip6Address= Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.648-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.648-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" setupComplete=true Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 20:59:13 private-chamber volumio[787]: xcb_connection_has_error() returned true Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 20:59:13 private-chamber volumio[787]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 20:59:13 private-chamber volumio[787]: xcb_connection_has_error() returned true Aug 31 20:59:13 private-chamber volumio[787]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 31 20:59:13 private-chamber volumio[787]: xcb_connection_has_error() returned true Aug 31 20:59:13 private-chamber volumio[787]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 31 20:59:13 private-chamber volumio[787]: Invalid card number. Aug 31 20:59:13 private-chamber volumio[787]: amixer -c 5 info | grep "E70" Aug 31 20:59:13 private-chamber volumio[787]: Invalid card number. Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.863-07:00 level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=5 Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.863-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" selectedOutputId=5 Aug 31 20:59:13 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:13 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:13 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.900-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" currentVersion=3.912 latestVersion=3.912 Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.900-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" status=UPDATE_STATUS_NONE progress=0 Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.900-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" userId= Aug 31 20:59:13 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:13.900-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" providers=3 Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 20:59:13 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 20:59:15 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:15.015-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" plugins=54 Aug 31 20:59:15 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:15 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:15 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:15.019-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 20:59:15 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:15.020-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id= title= Aug 31 20:59:20 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:20.122-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.50.143:47322 @ 0x2cbea20" latency=631.47009ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 20:59:20 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:20.141-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s Aug 31 20:59:20 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:20.592-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://securetoken.googleapis.com duration=447.936525ms Aug 31 20:59:20 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:20.800-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://database.volumio.cloud duration=653.730429ms Aug 31 20:59:20 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:20.882-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=736.369554ms Aug 31 20:59:21 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:21.013-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=868.872567ms Aug 31 20:59:21 private-chamber sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 20:59:21 private-chamber sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 20:59:21 private-chamber sudo[3366]: pam_unix(sudo:session): session closed for user root Aug 31 20:59:21 private-chamber sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 20:59:21 private-chamber sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 20:59:21 private-chamber sudo[3369]: pam_unix(sudo:session): session closed for user root Aug 31 20:59:21 private-chamber volumio[787]: verbose: New Socket.io Connection to 192.168.50.24 from 192.168.50.143 UA: Mozilla/5.0 (Linux; Android 11; motorola one 5G ace Build/RZKS31.Q3-45-16-8-19; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 20:59:21 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:21.436-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://google.com duration=1.293393412s Aug 31 20:59:21 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:21.439-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.296478751s Aug 31 20:59:21 private-chamber sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 20:59:21 private-chamber sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 20:59:21 private-chamber sudo[3372]: pam_unix(sudo:session): session closed for user root Aug 31 20:59:21 private-chamber sudo[3375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 20:59:21 private-chamber sudo[3375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 20:59:21 private-chamber sudo[3375]: pam_unix(sudo:session): session closed for user root Aug 31 20:59:21 private-chamber volumio[787]: verbose: New Socket.io Connection to 192.168.50.24 from 192.168.50.143 UA: Mozilla/5.0 (Linux; Android 11; motorola one 5G ace Build/RZKS31.Q3-45-16-8-19; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:21 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 20:59:21 private-chamber volumio[787]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 20:59:21 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:21 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:21 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:21 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:21 private-chamber volumio[787]: info: Listing playlists Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 20:59:21 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.016-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=2.874909357s Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.037-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://www.googleapis.com duration=2.889498324s Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.048-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.902601128s Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.050-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.902726552s Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.091-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=http://pushupdates.volumio.org duration=2.94503388s Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetQueue Aug 31 20:59:23 private-chamber volumio[787]: info: CoreStateMachine::getQueue Aug 31 20:59:23 private-chamber volumio[787]: info: CorePlayQueue::getQueue Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 20:59:23 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:23 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:23 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:23 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:23 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:23.778-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=http://cddb.volumio.org duration=3.630519449s Aug 31 20:59:24 private-chamber volumio5-onboarding[1159]: time=2026-08-31T20:59:24.497-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.143:47322 @ 0x2cbea20" latency=632.396022ms timeout=10s endpoint=http://plugins.volumio.org duration=4.350616169s Aug 31 20:59:25 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 20:59:25 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:25 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:25 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:25 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:25 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:25 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:25 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:31 private-chamber volumio[787]: Searching all installed plugins Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: , search Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 31 20:59:31 private-chamber volumio[787]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 31 20:59:31 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: creating Innertube instance... Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Aug 31 20:59:31 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Aug 31 20:59:31 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 20:59:32 private-chamber volumio[787]: Searching all installed plugins Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 31 20:59:32 private-chamber volumio[787]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 31 20:59:32 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Aug 31 20:59:37 private-chamber volumio[787]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Aug 31 20:59:37 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... Aug 31 20:59:37 private-chamber volumio[787]: info: An error occurred while querying SHOUTCAST Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin soundcloud timed out Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin youtube2 timed out Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin ytmusic timed out Aug 31 20:59:37 private-chamber volumio[787]: info: All search sources collected, pushing search results Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin mpd timed out Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin soundcloud timed out Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin youtube2 timed out Aug 31 20:59:37 private-chamber volumio[787]: error: Search in plugin ytmusic timed out Aug 31 20:59:37 private-chamber volumio[787]: info: All search sources collected, pushing search results Aug 31 20:59:37 private-chamber volumio[787]: Searching all installed plugins Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 31 20:59:37 private-chamber volumio[787]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Aug 31 20:59:37 private-chamber volumio[787]: Searching all installed plugins Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 31 20:59:37 private-chamber volumio[787]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 31 20:59:37 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Aug 31 20:59:41 private-chamber volumio[787]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Aug 31 20:59:41 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Aug 31 20:59:41 private-chamber volumio[787]: info: An error occurred while querying SHOUTCAST Aug 31 20:59:41 private-chamber volumio[787]: info: An error occurred while querying SHOUTCAST Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 20:59:41 private-chamber volumio[787]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 20:59:41 private-chamber volumio[787]: info: Received Get System Version Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 20:59:41 private-chamber volumio[787]: info: Received Get System Info Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 20:59:41 private-chamber volumio[787]: info: Discovery: Getting this device information Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 20:59:41 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 20:59:41 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 20:59:41 private-chamber volumio[787]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin soundcloud timed out Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin youtube2 timed out Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin ytmusic timed out Aug 31 20:59:42 private-chamber volumio[787]: info: All search sources collected, pushing search results Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin soundcloud timed out Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin youtube2 timed out Aug 31 20:59:42 private-chamber volumio[787]: error: Search in plugin ytmusic timed out Aug 31 20:59:42 private-chamber volumio[787]: info: All search sources collected, pushing search results Aug 31 20:59:43 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Aug 31 20:59:43 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Aug 31 20:59:43 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Aug 31 20:59:43 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Aug 31 20:59:43 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Aug 31 20:59:43 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Aug 31 20:59:43 private-chamber volumio[787]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 31 20:59:43 private-chamber volumio[787]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 31 20:59:51 private-chamber volumio[787]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Aug 31 20:59:51 private-chamber volumio[787]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Aug 31 20:59:54 private-chamber volumio[787]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Aug 31 20:59:54 private-chamber volumio[787]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Aug 31 20:59:54 private-chamber volumio[787]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:54 private-chamber volumio[787]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:55 private-chamber volumio[787]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:55 private-chamber volumio[787]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:55 private-chamber volumio[787]: error: Failed search in plugin soundcloud: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:55 private-chamber volumio[787]: error: Failed search in plugin soundcloud: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:56 private-chamber volumio[787]: error: Failed search in plugin youtube2: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:56 private-chamber systemd[1]: Starting Cleanup of Temporary Directories... Aug 31 20:59:56 private-chamber volumio[787]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 31 20:59:56 private-chamber systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Aug 31 20:59:56 private-chamber systemd[1]: Started Cleanup of Temporary Directories. Aug 31 20:59:56 private-chamber volumio[787]: error: Failed search in plugin youtube2: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 44, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Johnny Hartman - The Nearness Of You · 6:58', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { Aug 31 20:59:56 private-chamber volumio[787]: text: 'Johnny Hartman - The Nearness Of You · 6:58', Aug 31 20:59:56 private-chamber volumio[787]: startIndex: 0 Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 36, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: "Don't Take Your Love From Me · 8:37", Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: "Don't Take Your Love From Me · 8:37", startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 29, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'September in the Rain · 3:17', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'September in the Rain · 3:17', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 34, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'A Woman Always Understands · 2:46', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'A Woman Always Understands · 2:46', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 31, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: "They Say It's Wonderful · 5:21", Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: "They Say It's Wonderful · 5:21", startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 24, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { content: 'Dedicated To You · 5:33', commandRuns: [ [Object] ] }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'Dedicated To You · 5:33', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 49, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Almost Like Being In Love (Album Version) · 2:38', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { Aug 31 20:59:56 private-chamber volumio[787]: text: 'Almost Like Being In Love (Album Version) · 2:38', Aug 31 20:59:56 private-chamber volumio[787]: startIndex: 0 Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 39, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Once In A While (Album Version) · 2:30', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'Once In A While (Album Version) · 2:30', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: error: Failed search in plugin youtube2: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 44, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Johnny Hartman - The Nearness Of You · 6:58', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { Aug 31 20:59:56 private-chamber volumio[787]: text: 'Johnny Hartman - The Nearness Of You · 6:58', Aug 31 20:59:56 private-chamber volumio[787]: startIndex: 0 Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 36, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: "Don't Take Your Love From Me · 8:37", Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: "Don't Take Your Love From Me · 8:37", startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 29, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'September in the Rain · 3:17', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'September in the Rain · 3:17', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 34, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'A Woman Always Understands · 2:46', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'A Woman Always Understands · 2:46', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 49, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Almost Like Being In Love (Album Version) · 2:38', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { Aug 31 20:59:56 private-chamber volumio[787]: text: 'Almost Like Being In Love (Album Version) · 2:38', Aug 31 20:59:56 private-chamber volumio[787]: startIndex: 0 Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 39, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Once In A While (Album Version) · 2:30', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ { text: 'Once In A While (Album Version) · 2:30', startIndex: 0 } ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 63, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'john coltrane & johnny hartman / "my one and only love" · 4:58', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { Aug 31 20:59:56 private-chamber volumio[787]: text: 'john coltrane & johnny hartman / "my one and only love" · 4:58', Aug 31 20:59:56 private-chamber volumio[787]: startIndex: 0 Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Aug 31 20:59:56 private-chamber volumio[787]: command_run: { startIndex: 0, length: 40, onTap: { innertubeCommand: [Object] } }, Aug 31 20:59:56 private-chamber volumio[787]: input_data: { Aug 31 20:59:56 private-chamber volumio[787]: content: 'Johnny Hartman - For all we know · 5:44', Aug 31 20:59:56 private-chamber volumio[787]: commandRuns: [ [Object] ] Aug 31 20:59:56 private-chamber volumio[787]: }, Aug 31 20:59:56 private-chamber volumio[787]: parsed_runs: [ Aug 31 20:59:56 private-chamber volumio[787]: { text: 'Johnny Hartman - For all we know · 5:44', startIndex: 0 } Aug 31 20:59:56 private-chamber volumio[787]: ] Aug 31 20:59:56 private-chamber volumio[787]: } Aug 31 20:59:56 private-chamber volumio[787]: error: Failed search in plugin youtube2: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:56 private-chamber volumio[787]: error: Failed search in plugin soundcloud: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:57 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 20:59:57 private-chamber volumio[787]: info: CURURI: albums://John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204) Aug 31 20:59:57 private-chamber volumio[787]: info: Preload queue cleared Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/02 - 1963 - John Coltrane and Johnny Hartman - Dedicated to You - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/03 - 1963 - John Coltrane and Johnny Hartman - My One and Only Love - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/04 - 1963 - John Coltrane and Johnny Hartman - Lush Life - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/05 - 1963 - John Coltrane and Johnny Hartman - You Are Too Beautiful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/06 - 1963 - John Coltrane and Johnny Hartman - Autumn Serenade - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/07 - 1963 - Duke Ellington & John Coltrane - In a Sentimental Mood - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/08 - 1963 - Duke Ellington & John Coltrane - Take the Coltrane - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/09 - 1963 - Duke Ellington & John Coltrane - Big Nick - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/10 - 1963 - Duke Ellington & John Coltrane - Stevie - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/11 - 1963 - Duke Ellington & John Coltrane - My Little Brown Book - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/12 - 1963 - Duke Ellington & John Coltrane - Angelica - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/13 - 1963 - Duke Ellington & John Coltrane - The Feeling of Jazz - John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F01%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20They%20Say%20It's%20Wonderful%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/02 - 1963 - John Coltrane and Johnny Hartman - Dedicated to You - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F02%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20Dedicated%20to%20You%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/02 - 1963 - John Coltrane and Johnny Hartman - Dedicated to You - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/03 - 1963 - John Coltrane and Johnny Hartman - My One and Only Love - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F03%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20My%20One%20and%20Only%20Love%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/03 - 1963 - John Coltrane and Johnny Hartman - My One and Only Love - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Executing endpoint getSimilarAlbums Aug 31 20:59:57 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/04 - 1963 - John Coltrane and Johnny Hartman - Lush Life - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F04%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20Lush%20Life%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/04 - 1963 - John Coltrane and Johnny Hartman - Lush Life - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/05 - 1963 - John Coltrane and Johnny Hartman - You Are Too Beautiful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F05%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20You%20Are%20Too%20Beautiful%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/05 - 1963 - John Coltrane and Johnny Hartman - You Are Too Beautiful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Executing endpoint metavolumio Aug 31 20:59:57 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 20:59:57 private-chamber volumio[787]: info: Executing endpoint metavolumio Aug 31 20:59:57 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/06 - 1963 - John Coltrane and Johnny Hartman - Autumn Serenade - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20and%20Johnny%20Hartman/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F06%20-%201963%20-%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20Autumn%20Serenade%20-%20%20John%20Coltrane%20and%20Johnny%20Hartman%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/06 - 1963 - John Coltrane and Johnny Hartman - Autumn Serenade - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/07 - 1963 - Duke Ellington & John Coltrane - In a Sentimental Mood - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F07%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20In%20a%20Sentimental%20Mood%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/07 - 1963 - Duke Ellington & John Coltrane - In a Sentimental Mood - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/08 - 1963 - Duke Ellington & John Coltrane - Take the Coltrane - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F08%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20Take%20the%20Coltrane%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/08 - 1963 - Duke Ellington & John Coltrane - Take the Coltrane - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/09 - 1963 - Duke Ellington & John Coltrane - Big Nick - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F09%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20Big%20Nick%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/09 - 1963 - Duke Ellington & John Coltrane - Big Nick - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/10 - 1963 - Duke Ellington & John Coltrane - Stevie - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F10%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20Stevie%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/10 - 1963 - Duke Ellington & John Coltrane - Stevie - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/11 - 1963 - Duke Ellington & John Coltrane - My Little Brown Book - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F11%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20My%20Little%20Brown%20Book%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/11 - 1963 - Duke Ellington & John Coltrane - My Little Brown Book - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: error: Failed search in plugin soundcloud: Error: Unable to resolve or reject the same promise twice Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/12 - 1963 - Duke Ellington & John Coltrane - Angelica - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=Duke%20Ellington%20%26%20John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F12%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20Angelica%20-%20%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/12 - 1963 - Duke Ellington & John Coltrane - Angelica - Duke Ellington & John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 20:59:57 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/13 - 1963 - Duke Ellington & John Coltrane - The Feeling of Jazz - John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac in service mpd Aug 31 20:59:57 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204)/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20-%202017%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204)%2F13%20-%201963%20-%20Duke%20Ellington%20%26%20John%20Coltrane%20-%20The%20Feeling%20of%20Jazz%20-%20%20John%20Coltrane%20-%20The%20Classic%20Collaborations%201957-1963%20(Disc%204).flac&metadata=false Aug 31 20:59:57 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/13 - 1963 - Duke Ellington & John Coltrane - The Feeling of Jazz - John Coltrane - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 21:00:02 private-chamber volumio[787]: info: Preload queue cleared Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::volumioAddQueueItems Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::addQueueItems Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::addQueueItems Aug 31 21:00:02 private-chamber volumio[787]: info: Preload queue cleared Aug 31 21:00:02 private-chamber volumio[787]: info: Adding Item to queue: albums://John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204) Aug 31 21:00:02 private-chamber volumio[787]: info: Exploding uri albums://John%20Coltrane/The%20Classic%20Collaborations%201957-1963%20(Disc%204) in service mpd Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushQueue Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::saveQueue Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::updateTrackBlock Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrackBlock Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::volumioPlay Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::play index 0 Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::stop Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::play index undefined Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::startPlaybackTimer Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::clearAddPlayTracks NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand stop took 2 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand clear took 9 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand add "NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: error: updateQueue error: null Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 8ms Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand add "NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" took 6 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 5ms Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 5ms Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand play Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces system playlist update Aug 31 21:00:02 private-chamber volumio[787]: info: Ignoring MPD Status Update Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 4ms Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand play took 3 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 3ms Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 2ms Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:02 private-chamber volumio[787]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand status took 17 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:02 private-chamber volumio[787]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand status took 24 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand status took 26 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand clearerror took 12 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:02 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:02 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:02 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:02 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:02 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:02 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:02 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:02 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:02.981-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:02 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:02.982-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:02 private-chamber volumio[787]: info: ------------------------------ 69ms Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:02 private-chamber volumio[787]: info: Aug 31 21:00:02 private-chamber volumio[787]: ---------------------------- MPD announces state update: player Aug 31 21:00:02 private-chamber volumio[787]: info: ControllerMpd::getState Aug 31 21:00:02 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand status Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand status took 79 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand clearerror took 74 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand clearerror took 72 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 67 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 66 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand status took 31 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand status took 30 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseState Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 21:00:03 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:03 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:03 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.041-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.042-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.043-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.043-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:03 private-chamber volumio[787]: info: ------------------------------ 137ms Aug 31 21:00:03 private-chamber volumio[787]: info: ------------------------------ 136ms Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:03 private-chamber volumio[787]: verbose: ControllerMpd::parseTrackInfo Aug 31 21:00:03 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:03 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:03 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: ControllerMpd::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::servicePushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful","artist":"John Coltrane and Johnny Hartman","album":"The Classic Collaborations 1957-1963 (Disc 4)","uri":"NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac","trackType":"flac"} Aug 31 21:00:03 private-chamber volumio[787]: verbose: CURRENT POSITION 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState stateService pause Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::syncState currentStatus stop Aug 31 21:00:03 private-chamber volumio[787]: info: CoreStateMachine::pushState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioPushState Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output update for this device Aug 31 21:00:03 private-chamber volumio[787]: info: MRS: Pushing multiroomSync output Aug 31 21:00:03 private-chamber volumio[787]: info: CoreCommandRouter::volumioGetState Aug 31 21:00:03 private-chamber volumio[787]: info: CorePlayQueue::getTrack 0 Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.092-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.092-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.093-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.093-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.095-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" state=STATUS_STOPPED positionMs=0 volume= Aug 31 21:00:03 private-chamber volumio5-onboarding[1159]: time=2026-08-31T21:00:03.095-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.143:47322 @ 0x2cbea20" id="music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane - 2017 - The Classic Collaborations 1957-1963 (Disc 4)/01 - 1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful - John Coltrane and Johnny Hartman - The Classic Collaborations 1957-1963 (Disc 4).flac" title="1963 - John Coltrane and Johnny Hartman - They Say It's Wonderful" Aug 31 21:00:03 private-chamber volumio[787]: info: ------------------------------ 171ms Aug 31 21:00:03 private-chamber volumio[787]: info: ------------------------------ 123ms Aug 31 21:00:03 private-chamber volumio[787]: info: ------------------------------ 122ms Aug 31 21:00:06 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 21:00:06 private-chamber volumio[787]: info: CURURI: artists://John%20Coltrane%20%26%20Johnny%20Hartman Aug 31 21:00:06 private-chamber volumio[787]: info: Preload queue cleared Aug 31 21:00:06 private-chamber volumio[787]: info: Preload queue cleared Aug 31 21:00:06 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/01 - They Say It's Wonderful.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/02 - Dedicated To You.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/03 - My One And Only Love.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/04 - Lush Life.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Preloading song: music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/06 - Autumn Serenade.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/01 - They Say It's Wonderful.flac in service mpd Aug 31 21:00:06 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20%26%20Johnny%20Hartman/John%20Coltrane%20%26%20Johnny%20Hartman/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20%26%20Johnny%20Hartman%20-%20John%20Coltrane%20%26%20Johnny%20Hartman%20-%201963%20(1995)%2F01%20-%20They%20Say%20It's%20Wonderful.flac&metadata=false Aug 31 21:00:06 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/01 - They Say It's Wonderful.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/02 - Dedicated To You.flac in service mpd Aug 31 21:00:06 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20%26%20Johnny%20Hartman/John%20Coltrane%20%26%20Johnny%20Hartman/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20%26%20Johnny%20Hartman%20-%20John%20Coltrane%20%26%20Johnny%20Hartman%20-%201963%20(1995)%2F02%20-%20Dedicated%20To%20You.flac&metadata=false Aug 31 21:00:06 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/02 - Dedicated To You.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/03 - My One And Only Love.flac in service mpd Aug 31 21:00:06 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20%26%20Johnny%20Hartman/John%20Coltrane%20%26%20Johnny%20Hartman/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20%26%20Johnny%20Hartman%20-%20John%20Coltrane%20%26%20Johnny%20Hartman%20-%201963%20(1995)%2F03%20-%20My%20One%20And%20Only%20Love.flac&metadata=false Aug 31 21:00:06 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/03 - My One And Only Love.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Executing endpoint getSimilarArtists Aug 31 21:00:06 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 31 21:00:06 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/04 - Lush Life.flac in service mpd Aug 31 21:00:06 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20%26%20Johnny%20Hartman/John%20Coltrane%20%26%20Johnny%20Hartman/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20%26%20Johnny%20Hartman%20-%20John%20Coltrane%20%26%20Johnny%20Hartman%20-%201963%20(1995)%2F04%20-%20Lush%20Life.flac&metadata=false Aug 31 21:00:06 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/04 - Lush Life.flac Aug 31 21:00:06 private-chamber volumio[787]: info: Exploding uri music-library/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/06 - Autumn Serenade.flac in service mpd Aug 31 21:00:06 private-chamber volumio[787]: info: Executing endpoint metavolumio Aug 31 21:00:06 private-chamber volumio[787]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 21:00:06 private-chamber volumio[787]: info: ALBUMART /albumart?cacheid=30&web=John%20Coltrane%20%26%20Johnny%20Hartman/John%20Coltrane%20%26%20Johnny%20Hartman/extralarge&path=%2Fmnt%2FNAS%2FVanir-NAS%2FJohn%20Coltrane%2FJohn%20Coltrane%20%26%20Johnny%20Hartman%20-%20John%20Coltrane%20%26%20Johnny%20Hartman%20-%201963%20(1995)%2F06%20-%20Autumn%20Serenade.flac&metadata=false Aug 31 21:00:06 private-chamber volumio[787]: info: URI /mnt/NAS/Vanir-NAS/John Coltrane/John Coltrane & Johnny Hartman - John Coltrane & Johnny Hartman - 1963 (1995)/06 - Autumn Serenade.flac Aug 31 21:00:10 private-chamber volumio[787]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 21:00:10 private-chamber volumio[787]: Error: connect ECONNREFUSED 158.69.209.125:443 Aug 31 21:00:10 private-chamber volumio[787]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 31 21:00:10 private-chamber volumio[787]: errno: -111, Aug 31 21:00:10 private-chamber volumio[787]: code: 'ECONNREFUSED', Aug 31 21:00:10 private-chamber volumio[787]: syscall: 'connect', Aug 31 21:00:10 private-chamber volumio[787]: address: '158.69.209.125', Aug 31 21:00:10 private-chamber volumio[787]: port: 443 Aug 31 21:00:10 private-chamber volumio[787]: } Aug 31 21:00:10 private-chamber volumio[787]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 21:00:10 private-chamber sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 20:59 Aug 31 21:00:10 private-chamber sudo[3508]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"