-- Logs begin at Mon 2025-09-08 11:17:55 EEST, end at Mon 2025-09-08 11:37:28 EEST. -- Sep 08 11:36:00 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4477, instance 1, run: true Sep 08 11:36:01 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:01 rivoplus volumio[3192]: 1+1 records in Sep 08 11:36:01 rivoplus volumio[3192]: 1+1 records out Sep 08 11:36:01 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.0005215 s, 118 MB/s Sep 08 11:36:01 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4482, instance 1, run: true Sep 08 11:36:02 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:02 rivoplus volumio[3192]: 2+0 records in Sep 08 11:36:02 rivoplus volumio[3192]: 2+0 records out Sep 08 11:36:02 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000496667 s, 132 MB/s Sep 08 11:36:02 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4487, instance 1, run: true Sep 08 11:36:02 rivoplus volumio[3192]: 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 08 11:36:03 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:03 rivoplus volumio[3192]: 2+0 records in Sep 08 11:36:03 rivoplus volumio[3192]: 2+0 records out Sep 08 11:36:03 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000713416 s, 91.9 MB/s Sep 08 11:36:03 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4492, instance 1, run: true Sep 08 11:36:04 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:04 rivoplus volumio[3192]: 1+1 records in Sep 08 11:36:04 rivoplus volumio[3192]: 1+1 records out Sep 08 11:36:04 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.000516375 s, 120 MB/s Sep 08 11:36:04 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4500, instance 1, run: true Sep 08 11:36:05 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:05 rivoplus volumio[3192]: 2+0 records in Sep 08 11:36:05 rivoplus volumio[3192]: 2+0 records out Sep 08 11:36:05 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000514041 s, 127 MB/s Sep 08 11:36:05 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4505, instance 1, run: true Sep 08 11:36:06 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:36:06 rivoplus volumio[3192]: 5+1 records in Sep 08 11:36:06 rivoplus volumio[3192]: 5+1 records out Sep 08 11:36:06 rivoplus volumio[3192]: 182512 bytes (183 kB, 178 KiB) copied, 0.00157021 s, 116 MB/s Sep 08 11:36:06 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4510, instance 1, run: true Sep 08 11:36:06 rivoplus sudo[4514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:36:06 rivoplus sudo[4514]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:06 rivoplus sudo[4514]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:06 rivoplus sudo[4518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:36:06 rivoplus sudo[4518]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:06 rivoplus sudo[4518]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:06 rivoplus volumio[3192]: verbose: New Socket.io Connection to 192.168.0.140 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 11:36:06 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:06 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 11:36:06 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 11:36:07 rivoplus sudo[4522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:36:07 rivoplus sudo[4522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:07 rivoplus sudo[4522]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:07 rivoplus sudo[4525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:36:07 rivoplus sudo[4525]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:07 rivoplus sudo[4525]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:07 rivoplus volumio[3192]: verbose: New Socket.io Connection to 192.168.0.140 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 Sep 08 11:36:07 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:07 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:07 rivoplus volumio[3192]: 0 bytes copied, 0.000186292 s, 0.0 kB/s Sep 08 11:36:07 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4531, instance 1, run: true Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:07 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 11:36:07 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:36:07 rivoplus volumio[3192]: info: Listing playlists Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 11:36:07 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 11:36:08 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:08 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:08 rivoplus volumio[3192]: 0 bytes copied, 0.000176083 s, 0.0 kB/s Sep 08 11:36:08 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4536, instance 1, run: true Sep 08 11:36:09 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:09 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:09 rivoplus volumio[3192]: 0 bytes copied, 0.000190125 s, 0.0 kB/s Sep 08 11:36:09 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4541, instance 1, run: true Sep 08 11:36:10 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 11:36:10 rivoplus volumio[3192]: info: Received Get System Info Sep 08 11:36:10 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 11:36:10 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:36:10 rivoplus volumio[3192]: info: Discovery: Getting this device information Sep 08 11:36:10 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:10 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:10 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:36:10 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:10 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:10 rivoplus volumio[3192]: 0 bytes copied, 0.0001855 s, 0.0 kB/s Sep 08 11:36:10 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4563, instance 1, run: true Sep 08 11:36:11 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 11:36:11 rivoplus volumio[3192]: info: Received Get System Info Sep 08 11:36:11 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 11:36:11 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:36:11 rivoplus volumio[3192]: info: Discovery: Getting this device information Sep 08 11:36:11 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:11 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:11 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:36:11 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:11 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:11 rivoplus volumio[3192]: 0 bytes copied, 0.000338708 s, 0.0 kB/s Sep 08 11:36:11 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4568, instance 1, run: true Sep 08 11:36:12 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:12 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:12 rivoplus volumio[3192]: 0 bytes copied, 0.0001995 s, 0.0 kB/s Sep 08 11:36:12 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4573, instance 1, run: true Sep 08 11:36:13 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:13 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:13 rivoplus volumio[3192]: 0 bytes copied, 0.000340625 s, 0.0 kB/s Sep 08 11:36:13 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4578, instance 1, run: true Sep 08 11:36:15 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:15 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:15 rivoplus volumio[3192]: 0 bytes copied, 0.000182958 s, 0.0 kB/s Sep 08 11:36:15 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4583, instance 1, run: true Sep 08 11:36:16 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:16 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:16 rivoplus volumio[3192]: 0 bytes copied, 0.000194417 s, 0.0 kB/s Sep 08 11:36:16 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4588, instance 1, run: true Sep 08 11:36:17 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:17 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:17 rivoplus volumio[3192]: 0 bytes copied, 0.000182958 s, 0.0 kB/s Sep 08 11:36:17 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4593, instance 1, run: true Sep 08 11:36:18 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:18 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:18 rivoplus volumio[3192]: 0 bytes copied, 0.000350875 s, 0.0 kB/s Sep 08 11:36:18 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4598, instance 1, run: true Sep 08 11:36:19 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:19 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:19 rivoplus volumio[3192]: 0 bytes copied, 0.000326333 s, 0.0 kB/s Sep 08 11:36:19 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4603, instance 1, run: true Sep 08 11:36:20 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:20 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:20 rivoplus volumio[3192]: 0 bytes copied, 0.000190792 s, 0.0 kB/s Sep 08 11:36:20 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4625, instance 1, run: true Sep 08 11:36:20 rivoplus sudo[4629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:36:20 rivoplus sudo[4629]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:20 rivoplus sudo[4629]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:20 rivoplus sudo[4633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:36:20 rivoplus sudo[4633]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:20 rivoplus sudo[4633]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:20 rivoplus volumio[3192]: verbose: New Socket.io Connection to 192.168.0.140 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Sep 08 11:36:20 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 11:36:20 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:36:20 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 11:36:20 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 11:36:20 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:20 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 11:36:21 rivoplus sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:36:21 rivoplus sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:21 rivoplus sudo[4637]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:21 rivoplus sudo[4641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:36:21 rivoplus sudo[4641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:36:21 rivoplus sudo[4641]: pam_unix(sudo:session): session closed for user root Sep 08 11:36:21 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:21 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:21 rivoplus volumio[3192]: 0 bytes copied, 0.000169709 s, 0.0 kB/s Sep 08 11:36:21 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4647, instance 1, run: true Sep 08 11:36:21 rivoplus volumio[3192]: verbose: New Socket.io Connection to 192.168.0.140 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:21 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 11:36:21 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:36:21 rivoplus volumio[3192]: info: Listing playlists Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 11:36:21 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 11:36:22 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:22 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:22 rivoplus volumio[3192]: 0 bytes copied, 0.000189916 s, 0.0 kB/s Sep 08 11:36:22 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4652, instance 1, run: true Sep 08 11:36:23 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:23 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:23 rivoplus volumio[3192]: 0 bytes copied, 0.000332625 s, 0.0 kB/s Sep 08 11:36:23 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4660, instance 1, run: true Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 11:36:24 rivoplus volumio[3192]: info: Received Get System Info Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:36:24 rivoplus volumio[3192]: info: Discovery: Getting this device information Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:24 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:36:24 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:24 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:24 rivoplus volumio[3192]: 0 bytes copied, 0.000182333 s, 0.0 kB/s Sep 08 11:36:24 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4665, instance 1, run: true Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 11:36:24 rivoplus volumio[3192]: info: Received Get System Info Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:36:24 rivoplus volumio[3192]: info: Discovery: Getting this device information Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:24 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:24 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:36:25 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:25 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:25 rivoplus volumio[3192]: 0 bytes copied, 0.000334708 s, 0.0 kB/s Sep 08 11:36:25 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4670, instance 1, run: true Sep 08 11:36:26 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:26 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:26 rivoplus volumio[3192]: 0 bytes copied, 0.000190791 s, 0.0 kB/s Sep 08 11:36:26 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4675, instance 1, run: true Sep 08 11:36:27 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:27 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:27 rivoplus volumio[3192]: 0 bytes copied, 0.000337042 s, 0.0 kB/s Sep 08 11:36:27 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4680, instance 1, run: true Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 11:36:28 rivoplus volumio[3192]: xcb_connection_has_error() returned true Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 11:36:28 rivoplus volumio[3192]: xcb_connection_has_error() returned true Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Sep 08 11:36:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Sep 08 11:36:29 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:29 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:29 rivoplus volumio[3192]: 0 bytes copied, 0.000181042 s, 0.0 kB/s Sep 08 11:36:29 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4689, instance 1, run: true Sep 08 11:36:29 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:36:29 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:36:30 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:30 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:30 rivoplus volumio[3192]: 0 bytes copied, 0.00018775 s, 0.0 kB/s Sep 08 11:36:30 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4711, instance 1, run: true Sep 08 11:36:31 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:31 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:31 rivoplus volumio[3192]: 0 bytes copied, 0.000189709 s, 0.0 kB/s Sep 08 11:36:31 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4716, instance 1, run: true Sep 08 11:36:32 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:32 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:32 rivoplus volumio[3192]: 0 bytes copied, 0.0001855 s, 0.0 kB/s Sep 08 11:36:32 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4721, instance 1, run: true Sep 08 11:36:33 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:33 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:33 rivoplus volumio[3192]: 0 bytes copied, 0.00032225 s, 0.0 kB/s Sep 08 11:36:33 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4726, instance 1, run: true Sep 08 11:36:34 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:34 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:34 rivoplus volumio[3192]: 0 bytes copied, 0.000180375 s, 0.0 kB/s Sep 08 11:36:34 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4731, instance 1, run: true Sep 08 11:36:35 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:35 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:35 rivoplus volumio[3192]: 0 bytes copied, 0.000191333 s, 0.0 kB/s Sep 08 11:36:35 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4736, instance 1, run: true Sep 08 11:36:36 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:36 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:36 rivoplus volumio[3192]: 0 bytes copied, 0.000334125 s, 0.0 kB/s Sep 08 11:36:36 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4741, instance 1, run: true Sep 08 11:36:37 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:37 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:37 rivoplus volumio[3192]: 0 bytes copied, 0.000250583 s, 0.0 kB/s Sep 08 11:36:37 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4746, instance 1, run: true Sep 08 11:36:38 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:38 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:38 rivoplus volumio[3192]: 0 bytes copied, 0.000185625 s, 0.0 kB/s Sep 08 11:36:38 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4751, instance 1, run: true Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 11:36:39 rivoplus volumio[3192]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 08 11:36:39 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:39 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:39 rivoplus volumio[3192]: 0 bytes copied, 0.00018775 s, 0.0 kB/s Sep 08 11:36:39 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4756, instance 1, run: true Sep 08 11:36:39 rivoplus volumio[3192]: info: Received Get System Version Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 11:36:39 rivoplus volumio[3192]: info: Received Get System Info Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:36:39 rivoplus volumio[3192]: info: Discovery: Getting this device information Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:36:39 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:36:39 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:36:40 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:40 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:40 rivoplus volumio[3192]: 0 bytes copied, 0.000189333 s, 0.0 kB/s Sep 08 11:36:40 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4778, instance 1, run: true Sep 08 11:36:40 rivoplus volumio[3192]: info: CALLMETHOD: music_service inputs saveAdvancedAudioSettings [object Object] Sep 08 11:36:40 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: inputs , saveAdvancedAudioSettings Sep 08 11:36:40 rivoplus volumio[3192]: info: Setting HDMI I2S Mode to 13 (Gustard) Sep 08 11:36:41 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:41 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:41 rivoplus volumio[3192]: 0 bytes copied, 0.000189625 s, 0.0 kB/s Sep 08 11:36:41 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4783, instance 1, run: true Sep 08 11:36:43 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:43 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:43 rivoplus volumio[3192]: 0 bytes copied, 0.000187708 s, 0.0 kB/s Sep 08 11:36:43 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4788, instance 1, run: true Sep 08 11:36:44 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:44 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:44 rivoplus volumio[3192]: 0 bytes copied, 0.000335084 s, 0.0 kB/s Sep 08 11:36:44 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4796, instance 1, run: true Sep 08 11:36:45 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:45 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:45 rivoplus volumio[3192]: 0 bytes copied, 0.000179708 s, 0.0 kB/s Sep 08 11:36:45 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4801, instance 1, run: true Sep 08 11:36:46 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:46 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:46 rivoplus volumio[3192]: 0 bytes copied, 0.000193125 s, 0.0 kB/s Sep 08 11:36:46 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4806, instance 1, run: true Sep 08 11:36:47 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:47 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:47 rivoplus volumio[3192]: 0 bytes copied, 0.000334667 s, 0.0 kB/s Sep 08 11:36:47 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4811, instance 1, run: true Sep 08 11:36:48 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:48 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:48 rivoplus volumio[3192]: 0 bytes copied, 0.000191083 s, 0.0 kB/s Sep 08 11:36:48 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4816, instance 1, run: true Sep 08 11:36:49 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:49 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:49 rivoplus volumio[3192]: 0 bytes copied, 0.00018975 s, 0.0 kB/s Sep 08 11:36:49 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4822, instance 1, run: true Sep 08 11:36:50 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:50 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:50 rivoplus volumio[3192]: 0 bytes copied, 0.000393417 s, 0.0 kB/s Sep 08 11:36:50 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4844, instance 1, run: true Sep 08 11:36:51 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 11:36:51 rivoplus volumio[3192]: info: CURURI: music-library Sep 08 11:36:51 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetQueue Sep 08 11:36:51 rivoplus volumio[3192]: info: CoreStateMachine::getQueue Sep 08 11:36:51 rivoplus volumio[3192]: info: CorePlayQueue::getQueue Sep 08 11:36:51 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:36:51 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:51 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:51 rivoplus volumio[3192]: 0 bytes copied, 0.000185042 s, 0.0 kB/s Sep 08 11:36:51 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4849, instance 1, run: true Sep 08 11:36:52 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:52 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:52 rivoplus volumio[3192]: 0 bytes copied, 0.00017675 s, 0.0 kB/s Sep 08 11:36:52 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4854, instance 1, run: true Sep 08 11:36:53 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 11:36:53 rivoplus volumio[3192]: info: CURURI: music-library/USB Sep 08 11:36:53 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:36:53 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:53 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:53 rivoplus volumio[3192]: 0 bytes copied, 0.0001935 s, 0.0 kB/s Sep 08 11:36:53 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4859, instance 1, run: true Sep 08 11:36:54 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:54 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:54 rivoplus volumio[3192]: 0 bytes copied, 0.000188583 s, 0.0 kB/s Sep 08 11:36:54 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4864, instance 1, run: true Sep 08 11:36:55 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 11:36:55 rivoplus volumio[3192]: info: CURURI: music-library/USB/WD_ELEMENTS Sep 08 11:36:55 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:36:55 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:55 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:55 rivoplus volumio[3192]: 0 bytes copied, 0.000192167 s, 0.0 kB/s Sep 08 11:36:55 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4900, instance 1, run: true Sep 08 11:36:56 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:56 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:56 rivoplus volumio[3192]: 0 bytes copied, 0.000350917 s, 0.0 kB/s Sep 08 11:36:57 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4912, instance 1, run: true Sep 08 11:36:58 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:58 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:58 rivoplus volumio[3192]: 0 bytes copied, 0.000183667 s, 0.0 kB/s Sep 08 11:36:58 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4917, instance 1, run: true Sep 08 11:36:58 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 11:36:58 rivoplus volumio[3192]: info: CURURI: music-library/USB/WD_ELEMENTS/FUSION Sep 08 11:36:59 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:36:59 rivoplus volumio[3192]: 0+0 records in Sep 08 11:36:59 rivoplus volumio[3192]: 0+0 records out Sep 08 11:36:59 rivoplus volumio[3192]: 0 bytes copied, 0.000174042 s, 0.0 kB/s Sep 08 11:36:59 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4922, instance 1, run: true Sep 08 11:37:00 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:00 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:00 rivoplus volumio[3192]: 0 bytes copied, 0.000181625 s, 0.0 kB/s Sep 08 11:37:01 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4944, instance 1, run: true Sep 08 11:37:02 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:02 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:02 rivoplus volumio[3192]: 0 bytes copied, 0.00020125 s, 0.0 kB/s Sep 08 11:37:02 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4949, instance 1, run: true Sep 08 11:37:03 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:03 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:03 rivoplus volumio[3192]: 0 bytes copied, 0.000358708 s, 0.0 kB/s Sep 08 11:37:03 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4957, instance 1, run: true Sep 08 11:37:04 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:04 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:04 rivoplus volumio[3192]: 0 bytes copied, 0.000185375 s, 0.0 kB/s Sep 08 11:37:04 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4962, instance 1, run: true Sep 08 11:37:04 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 11:37:04 rivoplus volumio[3192]: info: CURURI: music-library/USB/WD_ELEMENTS/FUSION/Adam Holzman Sep 08 11:37:04 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:37:05 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:05 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:05 rivoplus volumio[3192]: 0 bytes copied, 0.0001795 s, 0.0 kB/s Sep 08 11:37:05 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4967, instance 1, run: true Sep 08 11:37:06 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:06 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:06 rivoplus volumio[3192]: 0 bytes copied, 0.000188292 s, 0.0 kB/s Sep 08 11:37:06 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4972, instance 1, run: true Sep 08 11:37:08 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:08 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:08 rivoplus volumio[3192]: 0 bytes copied, 0.000195042 s, 0.0 kB/s Sep 08 11:37:08 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4977, instance 1, run: true Sep 08 11:37:09 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:09 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:09 rivoplus volumio[3192]: 0 bytes copied, 0.000184292 s, 0.0 kB/s Sep 08 11:37:09 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 4983, instance 1, run: true Sep 08 11:37:10 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:10 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:10 rivoplus volumio[3192]: 0 bytes copied, 0.000196375 s, 0.0 kB/s Sep 08 11:37:10 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5005, instance 1, run: true Sep 08 11:37:11 rivoplus volumio[3192]: 0+0 records in Sep 08 11:37:11 rivoplus volumio[3192]: 0+0 records out Sep 08 11:37:11 rivoplus volumio[3192]: 0 bytes copied, 0.000192625 s, 0.0 kB/s Sep 08 11:37:11 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5010, instance 1, run: true Sep 08 11:37:11 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:37:11 rivoplus volumio[3192]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::ClearQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::stop Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::clearPlayQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::saveQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::addQueueItems Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::addQueueItems Sep 08 11:37:12 rivoplus volumio[3192]: info: Preload queue cleared Sep 08 11:37:12 rivoplus volumio[3192]: info: Adding Item to queue: music-library/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay Sep 08 11:37:12 rivoplus volumio[3192]: info: Exploding uri music-library/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay in service mpd Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F01%20-%20Ectoplasm.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F02%20-%20Bella%20Capri.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/02 - Bella Capri.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F03%20-%20A%20House%20is%20Not%20a%20Motel.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/03 - A House is Not a Motel.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F04%20-%20Phobia.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/04 - Phobia.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F05%20-%20Good%20Luck%20With%20Your%20Music.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/05 - Good Luck With Your Music.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F06%20-%20Are%20You%20High%EF%80%A5.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/06 - Are You High.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F07%20-%20Truth%20Decay.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/07 - Truth Decay.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F08%20-%20I%20Told%20You%20So.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/08 - I Told You So.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F09%20-%20Morphine%20Lollipop.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/09 - Morphine Lollipop.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F10%20-%20You%20Knew.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/10 - You Knew.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: ALBUMART /albumart?cacheid=497&web=Adam%20Holzman/Truth%20Decay/extralarge&path=%2Fmnt%2FUSB%2FWD_ELEMENTS%2FFUSION%2FAdam%20Holzman%2F2018%20-%20Truth%20Decay%2F11%20-%20Picking%20Through%20the%20Wreckage.flac&metadata=false Sep 08 11:37:12 rivoplus volumio[3192]: info: URI /mnt/USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/11 - Picking Through the Wreckage.flac Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::saveQueue Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::updateTrackBlock Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrackBlock Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPlay Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::play index 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::stop Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::play index undefined Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::startPlaybackTimer Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::clearAddPlayTracks USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand stop took 20 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand clear took 7 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand add "USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac" Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: error: updateQueue error: null Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 36ms Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand add "USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac" took 33 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 32ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 30ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 29ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 27ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 26ms Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand play Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces system playlist update Sep 08 11:37:12 rivoplus volumio[3192]: info: Ignoring MPD Status Update Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 22ms Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand play took 17 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 16ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 15ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 13ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 12ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 10ms Sep 08 11:37:12 rivoplus volumio[3192]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 17 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 16 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 15 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 13 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 11 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 10 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: Sep 08 11:37:12 rivoplus volumio[3192]: ---------------------------- MPD announces state update: player Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::getState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:37:12 rivoplus volumio[3192]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 08 11:37:12 rivoplus volumio[3192]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 08 11:37:12 rivoplus volumio[3192]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 163 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 155 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 154 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 154 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 153 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 153 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 152 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 149 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 146 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 144 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 141 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand status took 140 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseState Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 291ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 336ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 336ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 335ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 333ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 332ms Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 167 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 163 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ControllerMpd::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Ectoplasm","artist":"Adam Holzman","album":"Truth Decay","uri":"USB/WD_ELEMENTS/FUSION/Adam Holzman/2018 - Truth Decay/01 - Ectoplasm.flac","trackType":"flac"} Sep 08 11:37:12 rivoplus volumio[3192]: verbose: CURRENT POSITION 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState stateService play Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:37:12 rivoplus volumio[3192]: info: Received an update from plugin. extracting info from payload Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreStateMachine::pushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Sep 08 11:37:12 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Sep 08 11:37:12 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:37:12 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 490ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 473ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 472ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 470ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 468ms Sep 08 11:37:12 rivoplus volumio[3192]: info: ------------------------------ 467ms Sep 08 11:37:12 rivoplus volumio[3192]: info: Signalling Playback active due to playback status change Sep 08 11:37:13 rivoplus volumio[3192]: error: FusionDsp - WebSocket error: [object Object] Sep 08 11:37:13 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:13 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:13 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:13 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000521125 s, 126 MB/s Sep 08 11:37:13 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5020, instance 1, run: true Sep 08 11:37:13 rivoplus volumio[3192]: info: MCU Signalled Playback Active Sep 08 11:37:14 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:14 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:14 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:14 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000498459 s, 131 MB/s Sep 08 11:37:14 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5025, instance 1, run: true Sep 08 11:37:15 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:15 rivoplus volumio[3192]: 1+1 records in Sep 08 11:37:15 rivoplus volumio[3192]: 1+1 records out Sep 08 11:37:15 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.000496833 s, 124 MB/s Sep 08 11:37:15 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5035, instance 1, run: true Sep 08 11:37:16 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:16 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:16 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:16 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000561459 s, 117 MB/s Sep 08 11:37:16 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5044, instance 1, run: true Sep 08 11:37:18 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:18 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:18 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:18 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000493458 s, 133 MB/s Sep 08 11:37:18 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5051, instance 1, run: true Sep 08 11:37:19 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:19 rivoplus volumio[3192]: 1+1 records in Sep 08 11:37:19 rivoplus volumio[3192]: 1+1 records out Sep 08 11:37:19 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.000514334 s, 120 MB/s Sep 08 11:37:19 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5058, instance 1, run: true Sep 08 11:37:20 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:20 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:20 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:20 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000555125 s, 118 MB/s Sep 08 11:37:20 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5082, instance 1, run: true Sep 08 11:37:21 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:21 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:21 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:21 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.00051675 s, 127 MB/s Sep 08 11:37:21 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5089, instance 1, run: true Sep 08 11:37:23 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:23 rivoplus volumio[3192]: 1+1 records in Sep 08 11:37:23 rivoplus volumio[3192]: 1+1 records out Sep 08 11:37:23 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.000524291 s, 118 MB/s Sep 08 11:37:23 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5096, instance 1, run: true Sep 08 11:37:24 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:24 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:24 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:24 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000524708 s, 125 MB/s Sep 08 11:37:24 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5106, instance 1, run: true Sep 08 11:37:25 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:25 rivoplus volumio[3192]: 1+1 records in Sep 08 11:37:25 rivoplus volumio[3192]: 1+1 records out Sep 08 11:37:25 rivoplus volumio[3192]: 61712 bytes (62 kB, 60 KiB) copied, 0.000506 s, 122 MB/s Sep 08 11:37:25 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5113, instance 1, run: true Sep 08 11:37:27 rivoplus volumio[3192]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:37:27 rivoplus volumio[3192]: 2+0 records in Sep 08 11:37:27 rivoplus volumio[3192]: 2+0 records out Sep 08 11:37:27 rivoplus volumio[3192]: 65536 bytes (66 kB, 64 KiB) copied, 0.000530709 s, 123 MB/s Sep 08 11:37:27 rivoplus volumio[3192]: info: camilladsp spawned new process with pid 5120, instance 1, run: true Sep 08 11:37:28 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Sep 08 11:37:28 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Sep 08 11:37:28 rivoplus volumio[3192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:37:28 rivoplus volumio[3192]: TypeError: Cannot read property 'then' of undefined Sep 08 11:37:28 rivoplus volumio[3192]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Sep 08 11:37:28 rivoplus volumio[3192]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Sep 08 11:37:28 rivoplus volumio[3192]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Sep 08 11:37:28 rivoplus volumio[3192]: at Socket.emit (events.js:400:28) Sep 08 11:37:28 rivoplus volumio[3192]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 08 11:37:28 rivoplus volumio[3192]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 08 11:37:28 rivoplus volumio[3192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:37:28 rivoplus sudo[5134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 11:36 Sep 08 11:37:28 rivoplus sudo[5134]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"