-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Tue 2025-09-09 22:48:32 +07. -- Sep 09 22:47:00 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:00 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:00 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:01 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:01 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:01 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:02 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:02 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:02 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:02 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:02 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:02 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:03 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:03 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:03 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:04 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:04 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:04 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:05 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:05 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:05 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:05 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:05 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:05 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:06 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:07 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:08 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:08 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:08 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:09 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:09 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61210884 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60686596 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60948740 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60555524 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61473028 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60883204 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61407492 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60752132 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61014276 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61079812 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61276420 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61341956 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60621060 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60817668 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61145348 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61210884 in service upnp_browser Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60686596 in service upnp_browser Sep 09 22:47:09 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:09 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60948740 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:10 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60555524 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61473028 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60883204 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61407492 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60752132 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61014276 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61079812 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61276420 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61341956 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60621060 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$60817668 in service upnp_browser Sep 09 22:47:10 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$11338005$61145348 in service upnp_browser Sep 09 22:47:11 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:47:11 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:47:12 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:12 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:12 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:13 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:13 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:13 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:14 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:14 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:14 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:14 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:14 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:14 smsl-n100 sudo[4975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 22:47:14 smsl-n100 sudo[4975]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 22:47:14 smsl-n100 sudo[4975]: pam_unix(sudo:session): session closed for user root Sep 09 22:47:14 smsl-n100 sudo[4978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 22:47:14 smsl-n100 sudo[4978]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 22:47:14 smsl-n100 sudo[4978]: pam_unix(sudo:session): session closed for user root Sep 09 22:47:14 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.33 UA: Mozilla/5.0 (Linux; Android 15; SM-S936B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:47:15 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 22:47:15 smsl-n100 sudo[4981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 22:47:15 smsl-n100 sudo[4981]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 22:47:15 smsl-n100 sudo[4981]: pam_unix(sudo:session): session closed for user root Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 09 22:47:15 smsl-n100 sudo[4984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 22:47:15 smsl-n100 sudo[4984]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 22:47:15 smsl-n100 sudo[4984]: pam_unix(sudo:session): session closed for user root Sep 09 22:47:15 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.33 UA: Mozilla/5.0 (Linux; Android 15; SM-S936B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetQueue Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreStateMachine::getQueue Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CorePlayQueue::getQueue Sep 09 22:47:15 smsl-n100 volumio[1203]: info: Listing playlists Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 22:47:15 smsl-n100 volumio[1203]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 09 22:47:15 smsl-n100 volumio[1203]: info: Received Get System Info Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:47:15 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 09 22:47:15 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 09 22:47:16 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:16 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:16 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 22:47:17 smsl-n100 volumio[1203]: info: Received Get System Info Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:47:17 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:47:17 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:17 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:17 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:18 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:18 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:18 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:19 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:19 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:19 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:20 smsl-n100 volumio[1203]: error: error Sep 09 22:47:20 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:20 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:20 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:21 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:21 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:21 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:22 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:22 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:22 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:23 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:23 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:23 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:24 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:24 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:24 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:25 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:47:25 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:25 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:25 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:26 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:26 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:26 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:27 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:27 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:29 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:29 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:29 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:29 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:29 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:29 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:31 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:31 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:31 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:31 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:31 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:31 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:32 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:32 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:32 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:33 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 09 22:47:33 smsl-n100 volumio[1203]: info: Received Get System Version Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 22:47:33 smsl-n100 volumio[1203]: info: Received Get System Info Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:47:33 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:33 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:47:34 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:34 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:34 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:35 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:35 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:35 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:36 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:36 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:36 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:37 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:37 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:37 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:38 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:38 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:38 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:40 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:40 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:40 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:41 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:47:41 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:41 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 in service upnp_browser Sep 09 22:47:42 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:42 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:42 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 in service upnp_browser Sep 09 22:47:43 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::ClearQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::clearPlayQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPlay Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::play index 0 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::play index undefined Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:47:44 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:44 smsl-n100 volumio[1203]: info: [1757432864978] ControllerUPNPBrowser::clearAddPlayTrack Sep 09 22:47:44 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:47:44 smsl-n100 volumio[1203]: info: sendMpdCommand stop took 18 milliseconds Sep 09 22:47:45 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Sep 09 22:47:45 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: sendMpdCommand clear took 8 milliseconds Sep 09 22:47:45 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Sep 09 22:47:45 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:45 smsl-n100 volumio[1203]: error: updateQueue error: null Sep 09 22:47:45 smsl-n100 volumio[1203]: info: ------------------------------ 20ms Sep 09 22:47:45 smsl-n100 volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:45 smsl-n100 volumio[1203]: error: updateQueue error: null Sep 09 22:47:45 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:47:45 smsl-n100 volumio[1203]: info: ------------------------------ 130ms Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Sep 09 22:47:45 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" took 6 milliseconds Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:47:45 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Sep 09 22:47:45 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:45 smsl-n100 volumio[1203]: info: ------------------------------ 16ms Sep 09 22:47:45 smsl-n100 volumio[1203]: info: sendMpdCommand play took 11 milliseconds Sep 09 22:47:45 smsl-n100 volumio[1203]: info: ------------------------------ 11ms Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:45 smsl-n100 volumio[1203]: error: Serial API: Failed to decode command: 100, message: undefined Sep 09 22:47:45 smsl-n100 volumio[1203]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 09 22:47:45 smsl-n100 volumio[1203]: error: Serial API: Failed to decode command: 100, message: undefined Sep 09 22:47:45 smsl-n100 volumio[1203]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 09 22:47:45 smsl-n100 volumio[1203]: info: VolumeController::SetAlsaVolume0 Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:45 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: Sep 09 22:47:46 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:46 smsl-n100 volumio[1203]: info: Sep 09 22:47:46 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:46 smsl-n100 volumio[1203]: info: Sep 09 22:47:46 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand status took 27 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand status took 42 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 25 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand status took 23 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ------------------------------ 109ms Sep 09 22:47:46 smsl-n100 volumio[1203]: info: Sep 09 22:47:46 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 75 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 67 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 65 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand status took 20 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ------------------------------ 196ms Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ------------------------------ 189ms Sep 09 22:47:46 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 76 milliseconds Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50004228.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:46 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:46 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:46 smsl-n100 volumio[1203]: info: ------------------------------ 144ms Sep 09 22:47:46 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:46 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::ClearQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::clearPlayQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50004228 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50135300 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPlay Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::play index 1 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49742084 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49807620 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50790660 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50594052 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50659588 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50200836 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50397444 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50725124 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50462980 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50856196 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50528516 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49938692 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50331908 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50069764 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$49873156 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$9634069$50266372 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::play index undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:47:53 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:53 smsl-n100 volumio[1203]: info: [1757432873878] ControllerUPNPBrowser::clearAddPlayTrack Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Sep 09 22:47:53 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:53 smsl-n100 volumio[1203]: info: sendMpdCommand stop took 18 milliseconds Sep 09 22:47:53 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Sep 09 22:47:53 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:53 smsl-n100 volumio[1203]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:47:53 smsl-n100 volumio[1203]: info: sendMpdCommand clear took 7 milliseconds Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:47:53 smsl-n100 volumio[1203]: info: Sep 09 22:47:53 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:53 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:53 smsl-n100 volumio[1203]: error: updateQueue error: null Sep 09 22:47:53 smsl-n100 volumio[1203]: info: ------------------------------ 21ms Sep 09 22:47:53 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 09 22:47:53 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:53 smsl-n100 volumio[1203]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 09 22:47:53 smsl-n100 volumio[1203]: info: ------------------------------ 35ms Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand status took 112 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" took 6 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService stop Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: No code Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 207ms Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 114ms Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand play took 108 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 24ms Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:47:54 smsl-n100 volumio[1203]: info: Sep 09 22:47:54 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:47:54 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:54 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand status took 85 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand status took 79 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 76ms Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand status took 76 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 65 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 61 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 54 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 42 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 41 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 38 milliseconds Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$9634069$50135300.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:47:54 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 1 Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 334ms Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 334ms Sep 09 22:47:54 smsl-n100 volumio[1203]: info: ------------------------------ 308ms Sep 09 22:47:54 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:54 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:47:56 smsl-n100 ntpd[4907]: Soliciting pool server 202.29.58.130 Sep 09 22:47:57 smsl-n100 ntpd[4907]: Soliciting pool server 171.103.24.86 Sep 09 22:47:58 smsl-n100 ntpd[4907]: Soliciting pool server 203.159.70.33 Sep 09 22:47:58 smsl-n100 ntpd[4907]: Soliciting pool server 202.80.227.130 Sep 09 22:47:58 smsl-n100 ntpd[4907]: Soliciting pool server 202.80.227.130 Sep 09 22:48:02 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 09 22:48:02 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:48:04 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16777476 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17105156 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17039620 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17367300 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16711940 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17301764 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16646404 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17170692 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16908548 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16974084 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17236228 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Preloading song: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16843012 Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16777476 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17105156 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17039620 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17367300 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16711940 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17301764 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16646404 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17170692 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16908548 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16974084 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17236228 in service upnp_browser Sep 09 22:48:04 smsl-n100 volumio[1203]: info: Exploding uri upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16843012 in service upnp_browser Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::ClearQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::clearPlayQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16777476 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16777476 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPlay Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::play index 0 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::addQueueItems Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::addQueueItems Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Preload queue cleared Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17105156 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17105156 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17039620 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17039620 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17367300 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17367300 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16711940 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16711940 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17301764 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17301764 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16646404 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16646404 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17170692 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17170692 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16908548 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16908548 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16974084 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16974084 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17236228 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$17236228 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Adding Item to queue: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16843012 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Using cached record of: upnp/http://192.168.1.23:8200/ctl/ContentDir@A0$128$146$65813$3604757$3670293$5177621$5308693$16843012 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::stop Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::saveQueue Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::play index undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::updateTrackBlock Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrackBlock Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: [1757432886826] ControllerUPNPBrowser::clearAddPlayTrack Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Sep 09 22:48:06 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand stop took 18 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Sep 09 22:48:06 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand status took 8 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand clear took 8 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Sep 09 22:48:06 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:06 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:06 smsl-n100 volumio[1203]: error: updateQueue error: null Sep 09 22:48:06 smsl-n100 volumio[1203]: info: ------------------------------ 19ms Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:48:06 smsl-n100 volumio[1203]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 09 22:48:06 smsl-n100 volumio[1203]: info: ------------------------------ 38ms Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Sep 09 22:48:06 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:48:06 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand status took 56 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: info: sendMpdCommand add "http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4" took 5 milliseconds Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:48:06 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 09 22:48:06 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService stop Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: No code Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:06 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 134ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 88ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand play took 82 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 12ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces system playlist update Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Ignoring MPD Status Update Sep 09 22:48:07 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:48:07 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 34ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand status took 34 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Sep 09 22:48:07 smsl-n100 volumio[1203]: ---------------------------- MPD announces state update: player Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::getState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand status took 62 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand status took 59 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 45 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 38 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand status took 32 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseState Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 177ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 127 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand clearerror took 123 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 93 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 90 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: info: sendMpdCommand playlistinfo took 86 milliseconds Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ControllerMpd::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::servicePushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CorePlayQueue::getTrack 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp4,pn=,ext=.mp4","artist":null,"album":null,"uri":"http://192.168.1.23:8200/MediaItems/A0$128$146$65813$3604757$3670293$5177621$5308693$16777476.mp4?type=1,client=33,mime=audio/mp4,pn=,ext=.mp4","trackType":"mp4"} Sep 09 22:48:07 smsl-n100 volumio[1203]: verbose: CURRENT POSITION 0 Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState stateService pause Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreStateMachine::pushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioPushState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 325ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 320ms Sep 09 22:48:07 smsl-n100 volumio[1203]: info: ------------------------------ 290ms Sep 09 22:48:07 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:48:07 smsl-n100 volumio[1203]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:48:07 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:48:07 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:48:09 smsl-n100 volumio[1203]: error: error Sep 09 22:48:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:48:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:48:11 smsl-n100 volumio[1203]: info: Discovery: Getting this device information Sep 09 22:48:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetState Sep 09 22:48:11 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Retrieving Cloud Streaming UI Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Getting Tidal Cloud Configuration Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Getting Qobuz Cloud Configuration Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Asking plugin for UI Config Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Getting Spotify Cloud Configuration Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Asking plugin for UI Config Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Saving Spotify Acccount Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Got it Sep 09 22:48:27 smsl-n100 volumio[1203]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Got it Sep 09 22:48:27 smsl-n100 volumio[1203]: info: Got Tidal Cloud Configuration Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 09 22:48:27 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 09 22:48:31 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 09 22:48:31 smsl-n100 volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 09 22:48:31 smsl-n100 volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:48:31 smsl-n100 volumio[1203]: Error: getaddrinfo -3007 Sep 09 22:48:31 smsl-n100 volumio[1203]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Sep 09 22:48:31 smsl-n100 volumio[1203]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Sep 09 22:48:31 smsl-n100 volumio[1203]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Sep 09 22:48:31 smsl-n100 volumio[1203]: code: -3007, Sep 09 22:48:31 smsl-n100 volumio[1203]: errno: -3007, Sep 09 22:48:31 smsl-n100 volumio[1203]: syscall: 'getaddrinfo' Sep 09 22:48:31 smsl-n100 volumio[1203]: } Sep 09 22:48:31 smsl-n100 volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:48:32 smsl-n100 sudo[5216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-09 22:47 Sep 09 22:48:32 smsl-n100 sudo[5216]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"