Jun 02 21:43:00 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:00 volumio volumio[1295]: 6+1 records in Jun 02 21:43:00 volumio volumio[1295]: 6+1 records out Jun 02 21:43:00 volumio volumio[1295]: 228864 bytes (229 kB, 224 KiB) copied, 0.000949662 s, 241 MB/s Jun 02 21:43:00 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:00 volumio volumio[1295]: 2+0 records in Jun 02 21:43:00 volumio volumio[1295]: 2+0 records out Jun 02 21:43:00 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000473762 s, 138 MB/s Jun 02 21:43:01 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:01 volumio volumio[1295]: 7+1 records in Jun 02 21:43:01 volumio volumio[1295]: 7+1 records out Jun 02 21:43:01 volumio volumio[1295]: 261120 bytes (261 kB, 255 KiB) copied, 0.00118937 s, 220 MB/s Jun 02 21:43:01 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:01 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:01 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:01 volumio volumio[1295]: 2+0 records in Jun 02 21:43:01 volumio volumio[1295]: 2+0 records out Jun 02 21:43:01 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000394122 s, 166 MB/s Jun 02 21:43:02 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:02 volumio volumio[1295]: 12+1 records in Jun 02 21:43:02 volumio volumio[1295]: 12+1 records out Jun 02 21:43:02 volumio volumio[1295]: 424960 bytes (425 kB, 415 KiB) copied, 0.00155408 s, 273 MB/s Jun 02 21:43:02 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:02 volumio volumio[1295]: 11+1 records in Jun 02 21:43:02 volumio volumio[1295]: 11+1 records out Jun 02 21:43:02 volumio volumio[1295]: 392192 bytes (392 kB, 383 KiB) copied, 0.00148909 s, 263 MB/s Jun 02 21:43:03 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:03 volumio volumio[1295]: 13+1 records in Jun 02 21:43:03 volumio volumio[1295]: 13+1 records out Jun 02 21:43:03 volumio volumio[1295]: 457216 bytes (457 kB, 446 KiB) copied, 0.00190633 s, 240 MB/s Jun 02 21:43:03 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:03 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:03 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:03 volumio volumio[1295]: 6+1 records in Jun 02 21:43:03 volumio volumio[1295]: 6+1 records out Jun 02 21:43:03 volumio volumio[1295]: 228864 bytes (229 kB, 224 KiB) copied, 0.000943897 s, 242 MB/s Jun 02 21:43:04 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:04 volumio volumio[1295]: 19+1 records in Jun 02 21:43:04 volumio volumio[1295]: 19+1 records out Jun 02 21:43:04 volumio volumio[1295]: 653824 bytes (654 kB, 638 KiB) copied, 0.0021828 s, 300 MB/s Jun 02 21:43:04 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:04 volumio volumio[1295]: 1+1 records in Jun 02 21:43:04 volumio volumio[1295]: 1+1 records out Jun 02 21:43:04 volumio volumio[1295]: 65024 bytes (65 kB, 64 KiB) copied, 0.000696322 s, 93.4 MB/s Jun 02 21:43:05 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:05 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:05 volumio volumio[1295]: 5+1 records in Jun 02 21:43:05 volumio volumio[1295]: 5+1 records out Jun 02 21:43:05 volumio volumio[1295]: 196096 bytes (196 kB, 192 KiB) copied, 0.000832141 s, 236 MB/s Jun 02 21:43:05 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:05 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:05 volumio volumio[1295]: 2+0 records in Jun 02 21:43:05 volumio volumio[1295]: 2+0 records out Jun 02 21:43:05 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000274462 s, 239 MB/s Jun 02 21:43:06 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:06 volumio volumio[1295]: 7+1 records in Jun 02 21:43:06 volumio volumio[1295]: 7+1 records out Jun 02 21:43:06 volumio volumio[1295]: 261632 bytes (262 kB, 256 KiB) copied, 0.00105659 s, 248 MB/s Jun 02 21:43:06 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:06 volumio volumio[1295]: 11+1 records in Jun 02 21:43:06 volumio volumio[1295]: 11+1 records out Jun 02 21:43:06 volumio volumio[1295]: 392192 bytes (392 kB, 383 KiB) copied, 0.00156845 s, 250 MB/s Jun 02 21:43:07 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:07 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:07 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:07 volumio volumio[1295]: 7+1 records in Jun 02 21:43:07 volumio volumio[1295]: 7+1 records out Jun 02 21:43:07 volumio volumio[1295]: 261120 bytes (261 kB, 255 KiB) copied, 0.00100568 s, 260 MB/s Jun 02 21:43:07 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:07 volumio volumio[1295]: 2+0 records in Jun 02 21:43:07 volumio volumio[1295]: 2+0 records out Jun 02 21:43:07 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.001084 s, 60.5 MB/s Jun 02 21:43:08 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:08 volumio volumio[1295]: 8+1 records in Jun 02 21:43:08 volumio volumio[1295]: 8+1 records out Jun 02 21:43:08 volumio volumio[1295]: 293888 bytes (294 kB, 287 KiB) copied, 0.00116746 s, 252 MB/s Jun 02 21:43:08 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:08 volumio volumio[1295]: 2+0 records in Jun 02 21:43:08 volumio volumio[1295]: 2+0 records out Jun 02 21:43:08 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000635955 s, 103 MB/s Jun 02 21:43:09 volumio volumio[1295]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 02 21:43:09 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:09 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:09 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:09 volumio volumio[1295]: 10+1 records in Jun 02 21:43:09 volumio volumio[1295]: 10+1 records out Jun 02 21:43:09 volumio volumio[1295]: 359424 bytes (359 kB, 351 KiB) copied, 0.0015718 s, 229 MB/s Jun 02 21:43:09 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:09 volumio volumio[1295]: 2+0 records in Jun 02 21:43:09 volumio volumio[1295]: 2+0 records out Jun 02 21:43:09 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000250238 s, 262 MB/s Jun 02 21:43:10 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:10 volumio volumio[1295]: 2+0 records in Jun 02 21:43:10 volumio volumio[1295]: 2+0 records out Jun 02 21:43:10 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000251063 s, 261 MB/s Jun 02 21:43:10 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:10 volumio volumio[1295]: 9+1 records in Jun 02 21:43:10 volumio volumio[1295]: 9+1 records out Jun 02 21:43:10 volumio volumio[1295]: 326656 bytes (327 kB, 319 KiB) copied, 0.00135978 s, 240 MB/s Jun 02 21:43:11 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:11 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:11 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:11 volumio volumio[1295]: 13+1 records in Jun 02 21:43:11 volumio volumio[1295]: 13+1 records out Jun 02 21:43:11 volumio volumio[1295]: 457216 bytes (457 kB, 446 KiB) copied, 0.00166456 s, 275 MB/s Jun 02 21:43:12 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:12 volumio volumio[1295]: 15+1 records in Jun 02 21:43:12 volumio volumio[1295]: 15+1 records out Jun 02 21:43:12 volumio volumio[1295]: 523264 bytes (523 kB, 511 KiB) copied, 0.0111695 s, 46.8 MB/s Jun 02 21:43:12 volumio sudo[8201]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 21:43:12 volumio sudo[8201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:12 volumio sudo[8201]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:12 volumio sudo[8203]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 21:43:12 volumio sudo[8203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:12 volumio sudo[8203]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:12 volumio volumio[1295]: verbose: New Socket.io Connection to 192.168.2.153 from 192.168.2.59 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 21:43:12 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:12 volumio volumio[1295]: 1+1 records in Jun 02 21:43:12 volumio volumio[1295]: 1+1 records out Jun 02 21:43:12 volumio volumio[1295]: 65024 bytes (65 kB, 64 KiB) copied, 0.000259792 s, 250 MB/s Jun 02 21:43:12 volumio sudo[8213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 21:43:12 volumio sudo[8213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:12 volumio sudo[8213]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:12 volumio sudo[8217]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 21:43:12 volumio sudo[8217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:12 volumio sudo[8217]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:12 volumio volumio[1295]: verbose: New Socket.io Connection to 192.168.2.153 from 192.168.2.59 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::volumioGetState Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 21:43:12 volumio volumio[1295]: info: Listing playlists Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 21:43:12 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 21:43:13 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:13 volumio volumio[1295]: 2+0 records in Jun 02 21:43:13 volumio volumio[1295]: 2+0 records out Jun 02 21:43:13 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000266884 s, 246 MB/s Jun 02 21:43:13 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:13 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:13 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:13 volumio volumio[1295]: 11+1 records in Jun 02 21:43:13 volumio volumio[1295]: 11+1 records out Jun 02 21:43:13 volumio volumio[1295]: 392192 bytes (392 kB, 383 KiB) copied, 0.00171933 s, 228 MB/s Jun 02 21:43:14 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:14 volumio volumio[1295]: 1+1 records in Jun 02 21:43:14 volumio volumio[1295]: 1+1 records out Jun 02 21:43:14 volumio volumio[1295]: 62080 bytes (62 kB, 61 KiB) copied, 0.000257403 s, 241 MB/s Jun 02 21:43:14 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:14 volumio volumio[1295]: 1+1 records in Jun 02 21:43:14 volumio volumio[1295]: 1+1 records out Jun 02 21:43:14 volumio volumio[1295]: 64384 bytes (64 kB, 63 KiB) copied, 0.000245998 s, 262 MB/s Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 21:43:14 volumio volumio[1295]: info: Received Get System Info Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 21:43:14 volumio volumio[1295]: info: Discovery: Getting this device information Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::volumioGetState Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 21:43:14 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 02 21:43:15 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:15 volumio volumio[1295]: 9+1 records in Jun 02 21:43:15 volumio volumio[1295]: 9+1 records out Jun 02 21:43:15 volumio volumio[1295]: 327168 bytes (327 kB, 320 KiB) copied, 0.00133604 s, 245 MB/s Jun 02 21:43:15 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:15 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:15 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:15 volumio volumio[1295]: 13+1 records in Jun 02 21:43:15 volumio volumio[1295]: 13+1 records out Jun 02 21:43:15 volumio volumio[1295]: 457216 bytes (457 kB, 446 KiB) copied, 0.00167688 s, 273 MB/s Jun 02 21:43:16 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:16 volumio volumio[1295]: 2+0 records in Jun 02 21:43:16 volumio volumio[1295]: 2+0 records out Jun 02 21:43:16 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000576238 s, 114 MB/s Jun 02 21:43:16 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 02 21:43:16 volumio dbus-daemon[736]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.40' (uid=0 pid=8268 comm="timedatectl show --property=NTPSynchronized --valu") Jun 02 21:43:16 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 02 21:43:16 volumio dbus-daemon[736]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 02 21:43:16 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 02 21:43:16 volumio setdatetime-helper.sh[8267]: Time is not synchronized. Attempting to sync... Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::volumioNext Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::next Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 21:43:16 volumio volumio[1295]: info: ControllerMpd::next Jun 02 21:43:16 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand next Jun 02 21:43:16 volumio volumio[1295]: info: Jun 02 21:43:16 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jun 02 21:43:16 volumio volumio[1295]: info: sendMpdCommand next took 9 milliseconds Jun 02 21:43:16 volumio volumio[1295]: info: ControllerMpd::getState Jun 02 21:43:16 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jun 02 21:43:16 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jun 02 21:43:16 volumio volumio[1295]: verbose: ControllerMpd::parseState Jun 02 21:43:16 volumio volumio[1295]: info: ControllerMpd::pushState Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:16 volumio volumio[1295]: info: CorePlayQueue::getTrack 9 Jun 02 21:43:16 volumio volumio[1295]: 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} Jun 02 21:43:16 volumio volumio[1295]: verbose: CURRENT POSITION 9 Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:16 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:16 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jun 02 21:43:16 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:16 volumio volumio[1295]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22%D0%A1%D0%BA%D1%80%D0%B8%D0%BF%D0%B0%D1%87%22%2C%22artist%22%3A%22%D0%9A%D0%B0%D0%B7%D0%B0%D0%BD%20%D0%9A%D0%B0%D0%B7%D0%B8%D0%B5%D0%B2%22%2C%22album%22%3A%22%D0%A1%D0%B5%D0%B4%D0%BE%D0%B9%20(%D0%97%D0%BE%D0%BB%D0%BE%D1%82%D0%BE%D0%B5%20%D0%B8%D0%B7%D0%B4%D0%B0%D0%BD%D0%B8%D0%B5)%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F2cogWEU5CCqN1f0tHAdvZXrZDkN4J3TEyF7qvk7BoSI5Pkdi9kN8GzFcmMl90ypjWxRDGOK0nGq8dTTg%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_CUaV9_mLKw%22%2C%22playlistId%22%3A%22RDAMVM_CUaV9_mLKw%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_CUaV9_mLKw%22%2C%22playlistId%22%3A%22RDAMVM_CUaV9_mLKw%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Jun 02 21:43:16 volumio volumio[1295]: info: ------------------------------ 28ms Jun 02 21:43:16 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:16 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:16 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:16 volumio volumio[1295]: 0+0 records in Jun 02 21:43:16 volumio volumio[1295]: 0+0 records out Jun 02 21:43:16 volumio volumio[1295]: 0 bytes copied, 9.2221e-05 s, 0.0 kB/s Jun 02 21:43:16 volumio volumio[1295]: info: FusionDsp - Volumio is playing Jun 02 21:43:16 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:16 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:16 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:16 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:16 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 02 21:43:16 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 02 21:43:16 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 02 21:43:16 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:16 volumio sudo[8282]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 02 Jun 2025 18:43:17 GMT#015' Jun 02 21:43:16 volumio sudo[8282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 21:43:17 volumio setdatetime-helper.sh[8283]: Mon Jun 2 21:43:17 EEST 2025 Jun 02 21:43:17 volumio sudo[8282]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:17 volumio setdatetime-helper.sh[8267]: Time synchronized successfully. Jun 02 21:43:17 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 02 21:43:17 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 02 21:43:17 volumio volumio[1295]: 0+0 records in Jun 02 21:43:17 volumio volumio[1295]: 0+0 records out Jun 02 21:43:17 volumio volumio[1295]: 0 bytes copied, 0.000125637 s, 0.0 kB/s Jun 02 21:43:17 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:17 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 21:43:17 volumio volumio[1295]: info: sendMpdCommand stop took 6 milliseconds Jun 02 21:43:17 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 21:43:17 volumio volumio[1295]: info: Jun 02 21:43:17 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jun 02 21:43:17 volumio volumio[1295]: info: Ignoring MPD Status Update Jun 02 21:43:17 volumio volumio[1295]: info: sendMpdCommand clear took 1 milliseconds Jun 02 21:43:17 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-voxpm-3c2e.googlevideo.com/videoplayback?expire=1748911397&ei=xfA9aP_5L5TlxN8P7ZXW0AU&ip=192.162.210.97&id=o-AKtIil8gYt3hnfWC_RZMIm089zaGlwm1TujG5hh_QGIP&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748889797%2C&mh=zA&mm=31%2C29&mn=sn-voxpm-3c2e%2Csn-3c27sn7s&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&gcr=ua&initcwndbps=1948750&bui=AY1jyLOxF4xz3LJFIzuYw8zYsNYH1FUouQTszXacH2E0ZXRn1JNBxE7kG4QK2sIuP7oLZNzXxIWjQ7mB&spc=l3OVKdrboDmcwzCD481uC_1s95dnGXRUtnxohojG5UvtYrIs8vTi9obEyheidXu2UAc&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=m5DoveUiwKFdUIa0I6Kj06gQ&rqh=1&gir=yes&clen=3604354&dur=222.577&lmt=1698242512903660&mt=1748889211&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=WMIR6bEUQE2gZA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhANnSQj9EVKFE2CQ7TGboqAzVBbXd7E3CpPg6ZWJD8XMcAiA71X2r9pBIdkH3XJQnHTLXwP9xgjwJVix4GYS7ujeKJA%3D%3D&sig=AJfQdSswRQIgHS5t4srzkeNBw6mW94VAeSuIBiy9FtbCDWc0waeUyV0CIQCKkWRiswY2s7rwYw_hSpkN4bsRZYRQ6TNeB64fFIqyyQ%3D%3D&pot=MnjKk6_yKWJ0fsqve0uyn5Y_IA1zcSUvjUyRASEY36HaLj0kgupQRzAJvavbRk7gFgCZBG1bm7XeRtwPXztutZkDdi6QtkySdeEauk7WFu3oCTbteTGvmmKXEvBx1K3Yxq7B6jWewDaXiGsrSpN6LvEPjKzsXREKnIo%3D&cver=1.20250219.01.00&t.YouTube" Jun 02 21:43:17 volumio volumio[1295]: error: updateQueue error: null Jun 02 21:43:17 volumio volumio[1295]: info: ------------------------------ 1ms Jun 02 21:43:17 volumio volumio[1295]: info: sendMpdCommand addid "https://rr1---sn-voxpm-3c2e.googlevideo.com/videoplayback?expire=1748911397&ei=xfA9aP_5L5TlxN8P7ZXW0AU&ip=192.162.210.97&id=o-AKtIil8gYt3hnfWC_RZMIm089zaGlwm1TujG5hh_QGIP&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748889797%2C&mh=zA&mm=31%2C29&mn=sn-voxpm-3c2e%2Csn-3c27sn7s&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&gcr=ua&initcwndbps=1948750&bui=AY1jyLOxF4xz3LJFIzuYw8zYsNYH1FUouQTszXacH2E0ZXRn1JNBxE7kG4QK2sIuP7oLZNzXxIWjQ7mB&spc=l3OVKdrboDmcwzCD481uC_1s95dnGXRUtnxohojG5UvtYrIs8vTi9obEyheidXu2UAc&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=m5DoveUiwKFdUIa0I6Kj06gQ&rqh=1&gir=yes&clen=3604354&dur=222.577&lmt=1698242512903660&mt=1748889211&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=WMIR6bEUQE2gZA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhANnSQj9EVKFE2CQ7TGboqAzVBbXd7E3CpPg6ZWJD8XMcAiA71X2r9pBIdkH3XJQnHTLXwP9xgjwJVix4GYS7ujeKJA%3D%3D&sig=AJfQdSswRQIgHS5t4srzkeNBw6mW94VAeSuIBiy9FtbCDWc0waeUyV0CIQCKkWRiswY2s7rwYw_hSpkN4bsRZYRQ6TNeB64fFIqyyQ%3D%3D&pot=MnjKk6_yKWJ0fsqve0uyn5Y_IA1zcSUvjUyRASEY36HaLj0kgupQRzAJvavbRk7gFgCZBG1bm7XeRtwPXztutZkDdi6QtkySdeEauk7WFu3oCTbteTGvmmKXEvBx1K3Yxq7B6jWewDaXiGsrSpN6LvEPjKzsXREKnIo%3D&cver=1.20250219.01.00&t.YouTube" took 2 milliseconds Jun 02 21:43:17 volumio volumio[1295]: verbose: MPD COMMAND [object Object] Jun 02 21:43:17 volumio volumio[1295]: verbose: MPD COMMAND [object Object] Jun 02 21:43:17 volumio volumio[1295]: verbose: MPD COMMAND [object Object] Jun 02 21:43:17 volumio volumio[1295]: info: Jun 02 21:43:17 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jun 02 21:43:17 volumio volumio[1295]: info: Ignoring MPD Status Update Jun 02 21:43:17 volumio volumio[1295]: info: Jun 02 21:43:17 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jun 02 21:43:17 volumio volumio[1295]: info: Ignoring MPD Status Update Jun 02 21:43:17 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 21:43:17 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jun 02 21:43:17 volumio volumio[1295]: info: ------------------------------ 2ms Jun 02 21:43:17 volumio volumio[1295]: info: ------------------------------ 3ms Jun 02 21:43:17 volumio volumio[1295]: info: sendMpdCommand play took 3 milliseconds Jun 02 21:43:17 volumio volumio[1295]: 0+0 records in Jun 02 21:43:17 volumio volumio[1295]: 0+0 records out Jun 02 21:43:17 volumio volumio[1295]: 0 bytes copied, 0.00022944 s, 0.0 kB/s Jun 02 21:43:18 volumio volumio[1295]: 0+0 records in Jun 02 21:43:18 volumio volumio[1295]: 0+0 records out Jun 02 21:43:18 volumio volumio[1295]: 0 bytes copied, 0.000243784 s, 0.0 kB/s Jun 02 21:43:18 volumio volumio[1295]: info: Jun 02 21:43:18 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jun 02 21:43:18 volumio volumio[1295]: info: ControllerMpd::getState Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - ---- read samplerate, raw: Jun 02 21:43:18 volumio volumio[1295]: error: FusionDsp - invalid sample rate Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 02 21:43:18 volumio volumio[1295]: info: camilladsp service terminated, instance 1 Jun 02 21:43:18 volumio volumio[1295]: error: FusionDsp - File size not found in array! Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - Nulleq applied Jun 02 21:43:18 volumio volumio[1295]: info: camilladsp service started and running in background, instance 1 Jun 02 21:43:18 volumio volumio[1295]: info: Jun 02 21:43:18 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jun 02 21:43:18 volumio volumio[1295]: info: sendMpdCommand status took 15 milliseconds Jun 02 21:43:18 volumio volumio[1295]: info: ControllerMpd::getState Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::parseState Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 21:43:18 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jun 02 21:43:18 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::parseState Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jun 02 21:43:18 volumio volumio[1295]: info: ControllerMpd::pushState Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jun 02 21:43:18 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:18 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Скрипач","artist":"Казан Казиев","album":"Седой (Золотое издание)","uri":"https://rr1---sn-voxpm-3c2e.googlevideo.com/videoplayback?expire=1748911397&ei=xfA9aP_5L5TlxN8P7ZXW0AU&ip=192.162.210.97&id=o-AKtIil8gYt3hnfWC_RZMIm089zaGlwm1TujG5hh_QGIP&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748889797%2C&mh=zA&mm=31%2C29&mn=sn-voxpm-3c2e%2Csn-3c27sn7s&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&gcr=ua&initcwndbps=1948750&bui=AY1jyLOxF4xz3LJFIzuYw8zYsNYH1FUouQTszXacH2E0ZXRn1JNBxE7kG4QK2sIuP7oLZNzXxIWjQ7mB&spc=l3OVKdrboDmcwzCD481uC_1s95dnGXRUtnxohojG5UvtYrIs8vTi9obEyheidXu2UAc&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=m5DoveUiwKFdUIa0I6Kj06gQ&rqh=1&gir=yes&clen=3604354&dur=222.577&lmt=1698242512903660&mt=1748889211&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=WMIR6bEUQE2gZA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhANnSQj9EVKFE2CQ7TGboqAzVBbXd7E3CpPg6ZWJD8XMcAiA71X2r9pBIdkH3XJQnHTLXwP9xgjwJVix4GYS7ujeKJA%3D%3D&sig=AJfQdSswRQIgHS5t4srzkeNBw6mW94VAeSuIBiy9FtbCDWc0waeUyV0CIQCKkWRiswY2s7rwYw_hSpkN4bsRZYRQ6TNeB64fFIqyyQ%3D%3D&pot=MnjKk6_yKWJ0fsqve0uyn5Y_IA1zcSUvjUyRASEY36HaLj0kgupQRzAJvavbRk7gFgCZBG1bm7XeRtwPXztutZkDdi6QtkySdeEauk7WFu3oCTbteTGvmmKXEvBx1K3Yxq7B6jWewDaXiGsrSpN6LvEPjKzsXREKnIo%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 02 21:43:18 volumio volumio[1295]: verbose: CURRENT POSITION 10 Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jun 02 21:43:18 volumio volumio[1295]: info: ------------------------------ 20ms Jun 02 21:43:18 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 02 21:43:18 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jun 02 21:43:18 volumio volumio[1295]: info: ControllerMpd::pushState Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jun 02 21:43:18 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:18 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Скрипач","artist":"Казан Казиев","album":"Седой (Золотое издание)","uri":"https://rr1---sn-voxpm-3c2e.googlevideo.com/videoplayback?expire=1748911397&ei=xfA9aP_5L5TlxN8P7ZXW0AU&ip=192.162.210.97&id=o-AKtIil8gYt3hnfWC_RZMIm089zaGlwm1TujG5hh_QGIP&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748889797%2C&mh=zA&mm=31%2C29&mn=sn-voxpm-3c2e%2Csn-3c27sn7s&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&gcr=ua&initcwndbps=1948750&bui=AY1jyLOxF4xz3LJFIzuYw8zYsNYH1FUouQTszXacH2E0ZXRn1JNBxE7kG4QK2sIuP7oLZNzXxIWjQ7mB&spc=l3OVKdrboDmcwzCD481uC_1s95dnGXRUtnxohojG5UvtYrIs8vTi9obEyheidXu2UAc&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=m5DoveUiwKFdUIa0I6Kj06gQ&rqh=1&gir=yes&clen=3604354&dur=222.577&lmt=1698242512903660&mt=1748889211&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=WMIR6bEUQE2gZA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhANnSQj9EVKFE2CQ7TGboqAzVBbXd7E3CpPg6ZWJD8XMcAiA71X2r9pBIdkH3XJQnHTLXwP9xgjwJVix4GYS7ujeKJA%3D%3D&sig=AJfQdSswRQIgHS5t4srzkeNBw6mW94VAeSuIBiy9FtbCDWc0waeUyV0CIQCKkWRiswY2s7rwYw_hSpkN4bsRZYRQ6TNeB64fFIqyyQ%3D%3D&pot=MnjKk6_yKWJ0fsqve0uyn5Y_IA1zcSUvjUyRASEY36HaLj0kgupQRzAJvavbRk7gFgCZBG1bm7XeRtwPXztutZkDdi6QtkySdeEauk7WFu3oCTbteTGvmmKXEvBx1K3Yxq7B6jWewDaXiGsrSpN6LvEPjKzsXREKnIo%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 02 21:43:18 volumio volumio[1295]: verbose: CURRENT POSITION 10 Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jun 02 21:43:18 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:18 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:18 volumio volumio[1295]: info: ------------------------------ 28ms Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - Volumio is playing Jun 02 21:43:18 volumio volumio[1295]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 02 21:43:18 volumio volumio[1295]: info: FusionDsp - Volumio is playing Jun 02 21:43:18 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 02 21:43:18 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 21:43:18 volumio volumio[1295]: info: Received Get System Info Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 21:43:18 volumio volumio[1295]: info: Discovery: Getting this device information Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::volumioGetState Jun 02 21:43:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 21:43:18 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:18 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:19 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:19 volumio volumio[1295]: 0+0 records in Jun 02 21:43:19 volumio volumio[1295]: 0+0 records out Jun 02 21:43:19 volumio volumio[1295]: 0 bytes copied, 0.000213569 s, 0.0 kB/s Jun 02 21:43:19 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:19 volumio volumio[1295]: 2+0 records in Jun 02 21:43:19 volumio volumio[1295]: 2+0 records out Jun 02 21:43:19 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.00080519 s, 81.4 MB/s Jun 02 21:43:19 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:19 volumio volumio[1295]: 2+0 records in Jun 02 21:43:19 volumio volumio[1295]: 2+0 records out Jun 02 21:43:19 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000298561 s, 220 MB/s Jun 02 21:43:20 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:20 volumio volumio[1295]: 5+1 records in Jun 02 21:43:20 volumio volumio[1295]: 5+1 records out Jun 02 21:43:20 volumio volumio[1295]: 192784 bytes (193 kB, 188 KiB) copied, 0.000887494 s, 217 MB/s Jun 02 21:43:20 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:20 volumio volumio[1295]: 12+1 records in Jun 02 21:43:20 volumio volumio[1295]: 12+1 records out Jun 02 21:43:20 volumio volumio[1295]: 418608 bytes (419 kB, 409 KiB) copied, 0.00161947 s, 258 MB/s Jun 02 21:43:20 volumio volumio[1295]: info: VolumeController::SetAlsaVolume20 Jun 02 21:43:20 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:20 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:20 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:20 volumio volumio[1295]: info: FusionDsp - Volumio is playing Jun 02 21:43:20 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 02 21:43:20 volumio volumio[1295]: SPOTIFY: SPOTIFY VOLUME 39 Jun 02 21:43:20 volumio volumio[1295]: SPOTIFY: VOLUMIO VOLUME 20 Jun 02 21:43:20 volumio volumio[1295]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 02 21:43:20 volumio volumio[1295]: info: Setting Spotify Volume from Volumio: 20 Jun 02 21:43:20 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:20 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:20 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:20 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:20 volumio volumio[1295]: 1+1 records in Jun 02 21:43:20 volumio volumio[1295]: 1+1 records out Jun 02 21:43:20 volumio volumio[1295]: 63552 bytes (64 kB, 62 KiB) copied, 0.000273774 s, 232 MB/s Jun 02 21:43:21 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:21 volumio volumio[1295]: 12+1 records in Jun 02 21:43:21 volumio volumio[1295]: 12+1 records out Jun 02 21:43:21 volumio volumio[1295]: 424416 bytes (424 kB, 414 KiB) copied, 0.00156398 s, 271 MB/s Jun 02 21:43:21 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:21 volumio volumio[1295]: 1+1 records in Jun 02 21:43:21 volumio volumio[1295]: 1+1 records out Jun 02 21:43:21 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000267171 s, 231 MB/s Jun 02 21:43:21 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:21 volumio volumio[1295]: 11+1 records in Jun 02 21:43:21 volumio volumio[1295]: 11+1 records out Jun 02 21:43:21 volumio volumio[1295]: 389664 bytes (390 kB, 381 KiB) copied, 0.00156584 s, 249 MB/s Jun 02 21:43:22 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:22 volumio volumio[1295]: 7+1 records in Jun 02 21:43:22 volumio volumio[1295]: 7+1 records out Jun 02 21:43:22 volumio volumio[1295]: 258320 bytes (258 kB, 252 KiB) copied, 0.00113412 s, 228 MB/s Jun 02 21:43:22 volumio volumio[1295]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Jun 02 21:43:22 volumio volumio[1295]: info: Sending Spotify command with payload to local API: /player/volume Jun 02 21:43:22 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:22 volumio volumio[1295]: 1+1 records in Jun 02 21:43:22 volumio volumio[1295]: 1+1 records out Jun 02 21:43:22 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000265608 s, 232 MB/s Jun 02 21:43:22 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:22 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:22 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:22 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:22 volumio volumio[1295]: 2+0 records in Jun 02 21:43:22 volumio volumio[1295]: 2+0 records out Jun 02 21:43:22 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000250213 s, 262 MB/s Jun 02 21:43:23 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:23 volumio volumio[1295]: 1+1 records in Jun 02 21:43:23 volumio volumio[1295]: 1+1 records out Jun 02 21:43:23 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000249462 s, 247 MB/s Jun 02 21:43:23 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:23 volumio volumio[1295]: 26+1 records in Jun 02 21:43:23 volumio volumio[1295]: 26+1 records out Jun 02 21:43:23 volumio volumio[1295]: 877904 bytes (878 kB, 857 KiB) copied, 0.00343314 s, 256 MB/s Jun 02 21:43:23 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:23 volumio volumio[1295]: 2+0 records in Jun 02 21:43:23 volumio volumio[1295]: 2+0 records out Jun 02 21:43:23 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000259505 s, 253 MB/s Jun 02 21:43:24 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:24 volumio volumio[1295]: 2+0 records in Jun 02 21:43:24 volumio volumio[1295]: 2+0 records out Jun 02 21:43:24 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.00063179 s, 104 MB/s Jun 02 21:43:24 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:24 volumio volumio[1295]: 1+1 records in Jun 02 21:43:24 volumio volumio[1295]: 1+1 records out Jun 02 21:43:24 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000266283 s, 232 MB/s Jun 02 21:43:24 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:24 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:24 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:24 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:24 volumio volumio[1295]: 1+1 records in Jun 02 21:43:24 volumio volumio[1295]: 1+1 records out Jun 02 21:43:24 volumio volumio[1295]: 63328 bytes (63 kB, 62 KiB) copied, 0.000252164 s, 251 MB/s Jun 02 21:43:25 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:25 volumio volumio[1295]: 1+1 records in Jun 02 21:43:25 volumio volumio[1295]: 1+1 records out Jun 02 21:43:25 volumio volumio[1295]: 64576 bytes (65 kB, 63 KiB) copied, 0.000294384 s, 219 MB/s Jun 02 21:43:25 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:25 volumio volumio[1295]: 29+1 records in Jun 02 21:43:25 volumio volumio[1295]: 29+1 records out Jun 02 21:43:25 volumio volumio[1295]: 979648 bytes (980 kB, 957 KiB) copied, 0.00414227 s, 237 MB/s Jun 02 21:43:26 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:26 volumio volumio[1295]: 2+0 records in Jun 02 21:43:26 volumio volumio[1295]: 2+0 records out Jun 02 21:43:26 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000538607 s, 122 MB/s Jun 02 21:43:26 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:26 volumio volumio[1295]: 1+1 records in Jun 02 21:43:26 volumio volumio[1295]: 1+1 records out Jun 02 21:43:26 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000332416 s, 186 MB/s Jun 02 21:43:26 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:26 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:26 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:26 volumio volumio[1295]: 1+1 records in Jun 02 21:43:26 volumio volumio[1295]: 1+1 records out Jun 02 21:43:26 volumio volumio[1295]: 63216 bytes (63 kB, 62 KiB) copied, 0.000388244 s, 163 MB/s Jun 02 21:43:26 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:27 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:27 volumio volumio[1295]: 1+1 records in Jun 02 21:43:27 volumio volumio[1295]: 1+1 records out Jun 02 21:43:27 volumio volumio[1295]: 64576 bytes (65 kB, 63 KiB) copied, 0.000255678 s, 253 MB/s Jun 02 21:43:27 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:27 volumio volumio[1295]: 1+1 records in Jun 02 21:43:27 volumio volumio[1295]: 1+1 records out Jun 02 21:43:27 volumio volumio[1295]: 64992 bytes (65 kB, 63 KiB) copied, 0.000272673 s, 238 MB/s Jun 02 21:43:27 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:27 volumio volumio[1295]: 12+1 records in Jun 02 21:43:27 volumio volumio[1295]: 12+1 records out Jun 02 21:43:27 volumio volumio[1295]: 422432 bytes (422 kB, 413 KiB) copied, 0.00164565 s, 257 MB/s Jun 02 21:43:28 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:28 volumio volumio[1295]: 8+1 records in Jun 02 21:43:28 volumio volumio[1295]: 8+1 records out Jun 02 21:43:28 volumio volumio[1295]: 287264 bytes (287 kB, 281 KiB) copied, 0.00131252 s, 219 MB/s Jun 02 21:43:28 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:28 volumio volumio[1295]: 2+0 records in Jun 02 21:43:28 volumio volumio[1295]: 2+0 records out Jun 02 21:43:28 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000508254 s, 129 MB/s Jun 02 21:43:28 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:28 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:28 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:28 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:28 volumio volumio[1295]: 7+1 records in Jun 02 21:43:28 volumio volumio[1295]: 7+1 records out Jun 02 21:43:28 volumio volumio[1295]: 254496 bytes (254 kB, 249 KiB) copied, 0.00107801 s, 236 MB/s Jun 02 21:43:29 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:29 volumio volumio[1295]: 9+1 records in Jun 02 21:43:29 volumio volumio[1295]: 9+1 records out Jun 02 21:43:29 volumio volumio[1295]: 323856 bytes (324 kB, 316 KiB) copied, 0.00128076 s, 253 MB/s Jun 02 21:43:29 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:29 volumio volumio[1295]: 1+1 records in Jun 02 21:43:29 volumio volumio[1295]: 1+1 records out Jun 02 21:43:29 volumio volumio[1295]: 60208 bytes (60 kB, 59 KiB) copied, 0.000265733 s, 227 MB/s Jun 02 21:43:29 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:29 volumio volumio[1295]: 7+1 records in Jun 02 21:43:29 volumio volumio[1295]: 7+1 records out Jun 02 21:43:29 volumio volumio[1295]: 259824 bytes (260 kB, 254 KiB) copied, 0.00103963 s, 250 MB/s Jun 02 21:43:30 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:30 volumio volumio[1295]: 8+1 records in Jun 02 21:43:30 volumio volumio[1295]: 8+1 records out Jun 02 21:43:30 volumio volumio[1295]: 291360 bytes (291 kB, 285 KiB) copied, 0.00114796 s, 254 MB/s Jun 02 21:43:30 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:30 volumio volumio[1295]: 2+0 records in Jun 02 21:43:30 volumio volumio[1295]: 2+0 records out Jun 02 21:43:30 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.00214282 s, 30.6 MB/s Jun 02 21:43:30 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:30 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:30 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:30 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:30 volumio volumio[1295]: 1+1 records in Jun 02 21:43:30 volumio volumio[1295]: 1+1 records out Jun 02 21:43:30 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000407704 s, 151 MB/s Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 21:43:31 volumio volumio[1295]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 02 21:43:31 volumio volumio[1295]: info: Received Get System Version Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 21:43:31 volumio volumio[1295]: info: Received Get System Info Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 21:43:31 volumio volumio[1295]: info: Discovery: Getting this device information Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::volumioGetState Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 21:43:31 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:31 volumio volumio[1295]: 2+0 records in Jun 02 21:43:31 volumio volumio[1295]: 2+0 records out Jun 02 21:43:31 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000257353 s, 255 MB/s Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 21:43:31 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 21:43:31 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:31 volumio volumio[1295]: 1+1 records in Jun 02 21:43:31 volumio volumio[1295]: 1+1 records out Jun 02 21:43:31 volumio volumio[1295]: 62048 bytes (62 kB, 61 KiB) copied, 0.000471122 s, 132 MB/s Jun 02 21:43:32 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:32 volumio volumio[1295]: 8+1 records in Jun 02 21:43:32 volumio volumio[1295]: 8+1 records out Jun 02 21:43:32 volumio volumio[1295]: 294848 bytes (295 kB, 288 KiB) copied, 0.00119785 s, 246 MB/s Jun 02 21:43:32 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:32 volumio volumio[1295]: 9+1 records in Jun 02 21:43:32 volumio volumio[1295]: 9+1 records out Jun 02 21:43:32 volumio volumio[1295]: 320032 bytes (320 kB, 313 KiB) copied, 0.00126776 s, 252 MB/s Jun 02 21:43:32 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:32 volumio volumio[1295]: 2+0 records in Jun 02 21:43:32 volumio volumio[1295]: 2+0 records out Jun 02 21:43:32 volumio volumio[1295]: 65536 bytes (66 kB, 64 KiB) copied, 0.000654665 s, 100 MB/s Jun 02 21:43:32 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:32 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:32 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:33 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:33 volumio volumio[1295]: 1+1 records in Jun 02 21:43:33 volumio volumio[1295]: 1+1 records out Jun 02 21:43:33 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000239907 s, 257 MB/s Jun 02 21:43:33 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:33 volumio volumio[1295]: 8+1 records in Jun 02 21:43:33 volumio volumio[1295]: 8+1 records out Jun 02 21:43:33 volumio volumio[1295]: 291088 bytes (291 kB, 284 KiB) copied, 0.00112187 s, 259 MB/s Jun 02 21:43:33 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:33 volumio volumio[1295]: 10+1 records in Jun 02 21:43:33 volumio volumio[1295]: 10+1 records out Jun 02 21:43:33 volumio volumio[1295]: 356896 bytes (357 kB, 349 KiB) copied, 0.00134331 s, 266 MB/s Jun 02 21:43:34 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:34 volumio volumio[1295]: 1+1 records in Jun 02 21:43:34 volumio volumio[1295]: 1+1 records out Jun 02 21:43:34 volumio volumio[1295]: 61712 bytes (62 kB, 60 KiB) copied, 0.000559055 s, 110 MB/s Jun 02 21:43:34 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:34 volumio volumio[1295]: 2+1 records in Jun 02 21:43:34 volumio volumio[1295]: 2+1 records out Jun 02 21:43:34 volumio volumio[1295]: 69632 bytes (70 kB, 68 KiB) copied, 0.00075454 s, 92.3 MB/s Jun 02 21:43:34 volumio volumio[1295]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 02 21:43:34 volumio volumio[1295]: 18+1 records in Jun 02 21:43:34 volumio volumio[1295]: 18+1 records out Jun 02 21:43:34 volumio volumio[1295]: 619312 bytes (619 kB, 605 KiB) copied, 0.00819249 s, 75.6 MB/s Jun 02 21:43:34 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:34 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:34 volumio volumio[1295]: error: FusionDsp - Monitor WebSocket error: [object Object] Jun 02 21:43:35 volumio volumio[1295]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 02 21:43:35 volumio volumio[1295]: info: Preparing to save Alsa Options, stopping services first Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::volumioGetState Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::volumioPause Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::pause Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::servicePause Jun 02 21:43:35 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::servicePause Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 21:43:35 volumio volumio[1295]: info: ControllerMpd::pause Jun 02 21:43:35 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand pause Jun 02 21:43:35 volumio volumio[1295]: info: Saving Audio Output to: {"output_device":{"value":"0,1","label":"SPDIF"}} Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 21:43:35 volumio volumio[1295]: info: Found match in Cards Database: setting mixer for card SPDIF Jun 02 21:43:35 volumio volumio[1295]: info: Setting mixer Master for card SPDIF Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 21:43:35 volumio volumio[1295]: info: Updating Volume Controller Parameters: Device: 0,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 21:43:35 volumio volumio[1295]: info: Disabling external Volume Control Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 21:43:35 volumio volumio[1295]: info: Preparing to generate the ALSA configuration file Jun 02 21:43:35 volumio volumio[1295]: 0+0 records in Jun 02 21:43:35 volumio volumio[1295]: 0+0 records out Jun 02 21:43:35 volumio volumio[1295]: 0 bytes copied, 0.000233629 s, 0.0 kB/s Jun 02 21:43:35 volumio volumio[1295]: info: Jun 02 21:43:35 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jun 02 21:43:35 volumio volumio[1295]: info: sendMpdCommand pause took 142 milliseconds Jun 02 21:43:35 volumio volumio[1295]: info: ControllerMpd::getState Jun 02 21:43:35 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jun 02 21:43:35 volumio volumio[1295]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 02 21:43:35 volumio volumio[1295]: info: Reading ALSA contributions from plugins. Jun 02 21:43:35 volumio volumio[1295]: info: sendMpdCommand status took 20 milliseconds Jun 02 21:43:35 volumio volumio[1295]: verbose: ControllerMpd::parseState Jun 02 21:43:35 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 21:43:35 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 02 21:43:35 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jun 02 21:43:35 volumio volumio[1295]: info: ControllerMpd::pushState Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jun 02 21:43:35 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:35 volumio volumio[1295]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26325,"duration":223,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5 Kbps","isStreaming":false,"title":"Скрипач","artist":"Казан Казиев","album":"Седой (Золотое издание)","uri":"https://rr1---sn-voxpm-3c2e.googlevideo.com/videoplayback?expire=1748911397&ei=xfA9aP_5L5TlxN8P7ZXW0AU&ip=192.162.210.97&id=o-AKtIil8gYt3hnfWC_RZMIm089zaGlwm1TujG5hh_QGIP&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748889797%2C&mh=zA&mm=31%2C29&mn=sn-voxpm-3c2e%2Csn-3c27sn7s&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&gcr=ua&initcwndbps=1948750&bui=AY1jyLOxF4xz3LJFIzuYw8zYsNYH1FUouQTszXacH2E0ZXRn1JNBxE7kG4QK2sIuP7oLZNzXxIWjQ7mB&spc=l3OVKdrboDmcwzCD481uC_1s95dnGXRUtnxohojG5UvtYrIs8vTi9obEyheidXu2UAc&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=m5DoveUiwKFdUIa0I6Kj06gQ&rqh=1&gir=yes&clen=3604354&dur=222.577&lmt=1698242512903660&mt=1748889211&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=WMIR6bEUQE2gZA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhANnSQj9EVKFE2CQ7TGboqAzVBbXd7E3CpPg6ZWJD8XMcAiA71X2r9pBIdkH3XJQnHTLXwP9xgjwJVix4GYS7ujeKJA%3D%3D&sig=AJfQdSswRQIgHS5t4srzkeNBw6mW94VAeSuIBiy9FtbCDWc0waeUyV0CIQCKkWRiswY2s7rwYw_hSpkN4bsRZYRQ6TNeB64fFIqyyQ%3D%3D&pot=MnjKk6_yKWJ0fsqve0uyn5Y_IA1zcSUvjUyRASEY36HaLj0kgupQRzAJvavbRk7gFgCZBG1bm7XeRtwPXztutZkDdi6QtkySdeEauk7WFu3oCTbteTGvmmKXEvBx1K3Yxq7B6jWewDaXiGsrSpN6LvEPjKzsXREKnIo%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 02 21:43:35 volumio volumio[1295]: verbose: CURRENT POSITION 10 Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::syncState stateService pause Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus pause Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jun 02 21:43:35 volumio volumio[1295]: info: ------------------------------ 56ms Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 21:43:35 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:35 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:35 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 02 21:43:35 volumio volumio[1295]: 0+0 records in Jun 02 21:43:35 volumio volumio[1295]: 0+0 records out Jun 02 21:43:35 volumio volumio[1295]: 0 bytes copied, 0.000123049 s, 0.0 kB/s Jun 02 21:43:35 volumio volumio[1295]: info: VolumeController:: Volume=10 Mute =false Jun 02 21:43:35 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:35 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:35 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:35 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 02 21:43:35 volumio volumio[1295]: SPOTIFY: SPOTIFY VOLUME 20 Jun 02 21:43:35 volumio volumio[1295]: SPOTIFY: VOLUMIO VOLUME 10 Jun 02 21:43:35 volumio volumio[1295]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 02 21:43:35 volumio volumio[1295]: info: Setting Spotify Volume from Volumio: 10 Jun 02 21:43:35 volumio volumio[1295]: info: Asound.conf file written Jun 02 21:43:35 volumio sudo[8676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 02 21:43:35 volumio sudo[8676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio sudo[8676]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio volumio[1295]: info: Output device has changed, restarting MPD Jun 02 21:43:35 volumio volumio[1295]: info: Output device has changed, restarting Shairport Sync Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:35 volumio sudo[8682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 21:43:35 volumio sudo[8682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio sudo[8682]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio sudo[8685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 21:43:35 volumio sudo[8685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio volumio[1295]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 21:43:35 volumio volumio[1295]: info: Output device has changed, restarting MPD Jun 02 21:43:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 02 21:43:35 volumio volumio[1295]: info: Output device has changed, restarting Shairport Sync Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:35 volumio sudo[8692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 21:43:35 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 02 21:43:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 21:43:35 volumio systemd[1]: mpd.service: Consumed 11.075s CPU time. Jun 02 21:43:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 02 21:43:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 21:43:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 21:43:35 volumio sudo[8692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 21:43:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 21:43:35 volumio sudo[8694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 21:43:35 volumio sudo[8694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio sudo[8692]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio volumio[1295]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 21:43:35 volumio volumio[1295]: info: MPD Permissions set Jun 02 21:43:35 volumio volumio[1295]: info: MPD Permissions set Jun 02 21:43:35 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 02 21:43:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 21:43:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 21:43:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 21:43:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 21:43:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 21:43:35 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 21:43:35 volumio volumio[1295]: 0+0 records in Jun 02 21:43:35 volumio volumio[1295]: 0+0 records out Jun 02 21:43:35 volumio volumio[1295]: 0 bytes copied, 0.000255353 s, 0.0 kB/s Jun 02 21:43:35 volumio volumio[1295]: info: Starting Shairport Sync Jun 02 21:43:35 volumio volumio[1295]: info: Starting Shairport Sync Jun 02 21:43:35 volumio sudo[8730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 21:43:35 volumio sudo[8732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 21:43:35 volumio sudo[8730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio sudo[8732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 21:43:35 volumio sudo[8713]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 21:43:35 volumio sudo[8713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 21:43:35 volumio sudo[8713]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 02 21:43:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 02 21:43:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 21:43:35 volumio systemd[1]: shairport-sync.service: Consumed 6.739s CPU time. Jun 02 21:43:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 21:43:35 volumio sudo[8730]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 02 21:43:35 volumio volumio[1295]: info: Shairport-Sync Started Jun 02 21:43:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 02 21:43:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 21:43:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 02 21:43:35 volumio sudo[8732]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:35 volumio volumio[1295]: info: Shairport-Sync Started Jun 02 21:43:36 volumio volumio[1295]: 0+0 records in Jun 02 21:43:36 volumio volumio[1295]: 0+0 records out Jun 02 21:43:36 volumio volumio[1295]: 0 bytes copied, 0.000227813 s, 0.0 kB/s Jun 02 21:43:36 volumio volumio[1295]: 0+0 records in Jun 02 21:43:36 volumio volumio[1295]: 0+0 records out Jun 02 21:43:36 volumio volumio[1295]: 0 bytes copied, 0.000116521 s, 0.0 kB/s Jun 02 21:43:36 volumio volumio[1295]: 0+0 records in Jun 02 21:43:36 volumio volumio[1295]: 0+0 records out Jun 02 21:43:36 volumio volumio[1295]: 0 bytes copied, 0.000101539 s, 0.0 kB/s Jun 02 21:43:36 volumio volumio[1295]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Jun 02 21:43:36 volumio volumio[1295]: info: Sending Spotify command with payload to local API: /player/volume Jun 02 21:43:37 volumio mpd[8736]: 2025-06-02T21:43:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 21:43:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 02 21:43:37 volumio sudo[8694]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:37 volumio sudo[8685]: pam_unix(sudo:session): session closed for user root Jun 02 21:43:37 volumio volumio[1295]: error: MPD error: The expression evaluated to a falsy value: Jun 02 21:43:37 volumio volumio[1295]: assert.ok(self.idling) Jun 02 21:43:37 volumio volumio[1295]: error: The expression evaluated to a falsy value: Jun 02 21:43:37 volumio volumio[1295]: assert.ok(self.idling) Jun 02 21:43:37 volumio volumio[1295]: error: updateQueue error: null Jun 02 21:43:37 volumio volumio[1295]: 0+0 records in Jun 02 21:43:37 volumio volumio[1295]: 0+0 records out Jun 02 21:43:37 volumio volumio[1295]: 0 bytes copied, 9.4973e-05 s, 0.0 kB/s Jun 02 21:43:37 volumio volumio[1295]: 0+0 records in Jun 02 21:43:37 volumio volumio[1295]: 0+0 records out Jun 02 21:43:37 volumio volumio[1295]: 0 bytes copied, 0.000116145 s, 0.0 kB/s Jun 02 21:43:37 volumio volumio[1295]: 0+0 records in Jun 02 21:43:37 volumio volumio[1295]: 0+0 records out Jun 02 21:43:37 volumio volumio[1295]: 0 bytes copied, 0.000682616 s, 0.0 kB/s Jun 02 21:43:38 volumio volumio[1295]: 0+0 records in Jun 02 21:43:38 volumio volumio[1295]: 0+0 records out Jun 02 21:43:38 volumio volumio[1295]: 0 bytes copied, 0.000101601 s, 0.0 kB/s Jun 02 21:43:38 volumio volumio[1295]: 0+0 records in Jun 02 21:43:38 volumio volumio[1295]: 0+0 records out Jun 02 21:43:38 volumio volumio[1295]: 0 bytes copied, 0.000213044 s, 0.0 kB/s Jun 02 21:43:39 volumio volumio[1295]: 0+0 records in Jun 02 21:43:39 volumio volumio[1295]: 0+0 records out Jun 02 21:43:39 volumio volumio[1295]: 0 bytes copied, 0.000218909 s, 0.0 kB/s Jun 02 21:43:39 volumio volumio[1295]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 02 21:43:39 volumio volumio[1295]: info: CoreStateMachine::getcurrentVolume Jun 02 21:43:39 volumio volumio[1295]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 21:43:39 volumio volumio[1295]: info: VolumeController:: Volume=10 Mute =false Jun 02 21:43:39 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:39 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:39 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jun 02 21:43:39 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jun 02 21:43:39 volumio volumio[1295]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 21:43:39 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:39 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:39 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 02 21:43:39 volumio volumio[1295]: info: VolumeController:: Volume=10 Mute =false Jun 02 21:43:39 volumio volumio[1295]: info: CoreStateMachine::pushState Jun 02 21:43:39 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jun 02 21:43:39 volumio volumio[1295]: info: FusionDsp - Volumio is not playing Jun 02 21:43:39 volumio volumio[1295]: info: FusionDsp - Clipped samples monitor stopped Jun 02 21:43:39 volumio volumio[1295]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 02 21:43:39 volumio volumio[1295]: 0+0 records in Jun 02 21:43:39 volumio volumio[1295]: 0+0 records out Jun 02 21:43:39 volumio volumio[1295]: 0 bytes copied, 9.7674e-05 s, 0.0 kB/s Jun 02 21:43:39 volumio volumio[1295]: 0+0 records in Jun 02 21:43:39 volumio volumio[1295]: 0+0 records out Jun 02 21:43:39 volumio volumio[1295]: 0 bytes copied, 0.000102364 s, 0.0 kB/s Jun 02 21:43:40 volumio volumio[1295]: 0+0 records in Jun 02 21:43:40 volumio volumio[1295]: 0+0 records out Jun 02 21:43:40 volumio volumio[1295]: 0 bytes copied, 8.9658e-05 s, 0.0 kB/s Jun 02 21:43:40 volumio volumio[1295]: 0+0 records in Jun 02 21:43:40 volumio volumio[1295]: 0+0 records out Jun 02 21:43:40 volumio volumio[1295]: 0 bytes copied, 0.000120172 s, 0.0 kB/s Jun 02 21:43:40 volumio volumio[1295]: 0+0 records in Jun 02 21:43:40 volumio volumio[1295]: 0+0 records out Jun 02 21:43:40 volumio volumio[1295]: 0 bytes copied, 9.8699e-05 s, 0.0 kB/s Jun 02 21:43:41 volumio volumio[1295]: 0+0 records in Jun 02 21:43:41 volumio volumio[1295]: 0+0 records out Jun 02 21:43:41 volumio volumio[1295]: 0 bytes copied, 0.000231315 s, 0.0 kB/s Jun 02 21:43:41 volumio volumio[1295]: 0+0 records in Jun 02 21:43:41 volumio volumio[1295]: 0+0 records out Jun 02 21:43:41 volumio volumio[1295]: 0 bytes copied, 0.000320623 s, 0.0 kB/s Jun 02 21:43:41 volumio volumio[1295]: info: CoreCommandRouter::volumioNext Jun 02 21:43:41 volumio volumio[1295]: info: CoreStateMachine::next Jun 02 21:43:41 volumio volumio[1295]: info: ControllerMpd::next Jun 02 21:43:41 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand next Jun 02 21:43:42 volumio volumio[1295]: 0+0 records in Jun 02 21:43:42 volumio volumio[1295]: 0+0 records out Jun 02 21:43:42 volumio volumio[1295]: 0 bytes copied, 0.000107678 s, 0.0 kB/s Jun 02 21:43:42 volumio volumio[1295]: 0+0 records in Jun 02 21:43:42 volumio volumio[1295]: 0+0 records out Jun 02 21:43:42 volumio volumio[1295]: 0 bytes copied, 8.8694e-05 s, 0.0 kB/s Jun 02 21:43:42 volumio volumio[1295]: 0+0 records in Jun 02 21:43:42 volumio volumio[1295]: 0+0 records out Jun 02 21:43:42 volumio volumio[1295]: 0 bytes copied, 0.00028448 s, 0.0 kB/s Jun 02 21:43:43 volumio volumio[1295]: 0+0 records in Jun 02 21:43:43 volumio volumio[1295]: 0+0 records out Jun 02 21:43:43 volumio volumio[1295]: 0 bytes copied, 0.000229289 s, 0.0 kB/s Jun 02 21:43:43 volumio volumio[1295]: 0+0 records in Jun 02 21:43:43 volumio volumio[1295]: 0+0 records out Jun 02 21:43:43 volumio volumio[1295]: 0 bytes copied, 9.5911e-05 s, 0.0 kB/s Jun 02 21:43:43 volumio volumio[1295]: 0+0 records in Jun 02 21:43:43 volumio volumio[1295]: 0+0 records out Jun 02 21:43:43 volumio volumio[1295]: 0 bytes copied, 8.8719e-05 s, 0.0 kB/s Jun 02 21:43:44 volumio volumio[1295]: 0+0 records in Jun 02 21:43:44 volumio volumio[1295]: 0+0 records out Jun 02 21:43:44 volumio volumio[1295]: 0 bytes copied, 9.4247e-05 s, 0.0 kB/s Jun 02 21:43:44 volumio volumio[1295]: 0+0 records in Jun 02 21:43:44 volumio volumio[1295]: 0+0 records out Jun 02 21:43:44 volumio volumio[1295]: 0 bytes copied, 0.00010085 s, 0.0 kB/s Jun 02 21:43:44 volumio volumio[1295]: 0+0 records in Jun 02 21:43:44 volumio volumio[1295]: 0+0 records out Jun 02 21:43:44 volumio volumio[1295]: 0 bytes copied, 0.000228814 s, 0.0 kB/s Jun 02 21:43:45 volumio volumio[1295]: 0+0 records in Jun 02 21:43:45 volumio volumio[1295]: 0+0 records out Jun 02 21:43:45 volumio volumio[1295]: 0 bytes copied, 9.3772e-05 s, 0.0 kB/s Jun 02 21:43:45 volumio volumio[1295]: 0+0 records in Jun 02 21:43:45 volumio volumio[1295]: 0+0 records out Jun 02 21:43:45 volumio volumio[1295]: 0 bytes copied, 0.000224499 s, 0.0 kB/s Jun 02 21:43:45 volumio volumio[1295]: 0+0 records in Jun 02 21:43:45 volumio volumio[1295]: 0+0 records out Jun 02 21:43:45 volumio volumio[1295]: 0 bytes copied, 0.000290008 s, 0.0 kB/s Jun 02 21:43:46 volumio volumio[1295]: 0+0 records in Jun 02 21:43:46 volumio volumio[1295]: 0+0 records out Jun 02 21:43:46 volumio volumio[1295]: 0 bytes copied, 0.000116058 s, 0.0 kB/s Jun 02 21:43:46 volumio volumio[1295]: 0+0 records in Jun 02 21:43:46 volumio volumio[1295]: 0+0 records out Jun 02 21:43:46 volumio volumio[1295]: 0 bytes copied, 8.9833e-05 s, 0.0 kB/s Jun 02 21:43:46 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 02 21:43:46 volumio volumio[1295]: 0+0 records in Jun 02 21:43:46 volumio volumio[1295]: 0+0 records out Jun 02 21:43:46 volumio volumio[1295]: 0 bytes copied, 0.000271048 s, 0.0 kB/s Jun 02 21:43:47 volumio volumio[1295]: 0+0 records in Jun 02 21:43:47 volumio volumio[1295]: 0+0 records out Jun 02 21:43:47 volumio volumio[1295]: 0 bytes copied, 0.000239119 s, 0.0 kB/s Jun 02 21:43:47 volumio volumio[1295]: 0+0 records in Jun 02 21:43:47 volumio volumio[1295]: 0+0 records out Jun 02 21:43:47 volumio volumio[1295]: 0 bytes copied, 9.8774e-05 s, 0.0 kB/s Jun 02 21:43:47 volumio volumio[1295]: 0+0 records in Jun 02 21:43:47 volumio volumio[1295]: 0+0 records out Jun 02 21:43:47 volumio volumio[1295]: 0 bytes copied, 0.000137144 s, 0.0 kB/s Jun 02 21:43:48 volumio volumio[1295]: 0+0 records in Jun 02 21:43:48 volumio volumio[1295]: 0+0 records out Jun 02 21:43:48 volumio volumio[1295]: 0 bytes copied, 9.93e-05 s, 0.0 kB/s Jun 02 21:43:48 volumio volumio[1295]: 0+0 records in Jun 02 21:43:48 volumio volumio[1295]: 0+0 records out Jun 02 21:43:48 volumio volumio[1295]: 0 bytes copied, 0.000112443 s, 0.0 kB/s Jun 02 21:43:48 volumio volumio[1295]: 0+0 records in Jun 02 21:43:48 volumio volumio[1295]: 0+0 records out Jun 02 21:43:48 volumio volumio[1295]: 0 bytes copied, 0.000246961 s, 0.0 kB/s Jun 02 21:43:49 volumio volumio[1295]: 0+0 records in Jun 02 21:43:49 volumio volumio[1295]: 0+0 records out Jun 02 21:43:49 volumio volumio[1295]: 0 bytes copied, 0.000130552 s, 0.0 kB/s Jun 02 21:43:49 volumio volumio[1295]: info: CoreCommandRouter::volumioNext Jun 02 21:43:49 volumio volumio[1295]: info: CoreStateMachine::next Jun 02 21:43:49 volumio volumio[1295]: info: ControllerMpd::next Jun 02 21:43:49 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand next Jun 02 21:43:49 volumio volumio[1295]: 0+0 records in Jun 02 21:43:49 volumio volumio[1295]: 0+0 records out Jun 02 21:43:49 volumio volumio[1295]: 0 bytes copied, 0.000234255 s, 0.0 kB/s Jun 02 21:43:50 volumio volumio[1295]: 0+0 records in Jun 02 21:43:50 volumio volumio[1295]: 0+0 records out Jun 02 21:43:50 volumio volumio[1295]: 0 bytes copied, 0.000128477 s, 0.0 kB/s Jun 02 21:43:50 volumio volumio[1295]: 0+0 records in Jun 02 21:43:50 volumio volumio[1295]: 0+0 records out Jun 02 21:43:50 volumio volumio[1295]: 0 bytes copied, 0.000132341 s, 0.0 kB/s Jun 02 21:43:50 volumio volumio[1295]: 0+0 records in Jun 02 21:43:50 volumio volumio[1295]: 0+0 records out Jun 02 21:43:50 volumio volumio[1295]: 0 bytes copied, 0.000219297 s, 0.0 kB/s Jun 02 21:43:51 volumio volumio[1295]: 0+0 records in Jun 02 21:43:51 volumio volumio[1295]: 0+0 records out Jun 02 21:43:51 volumio volumio[1295]: 0 bytes copied, 9.2396e-05 s, 0.0 kB/s Jun 02 21:43:51 volumio volumio[1295]: 0+0 records in Jun 02 21:43:51 volumio volumio[1295]: 0+0 records out Jun 02 21:43:51 volumio volumio[1295]: 0 bytes copied, 9.1133e-05 s, 0.0 kB/s Jun 02 21:43:51 volumio volumio[1295]: 0+0 records in Jun 02 21:43:51 volumio volumio[1295]: 0+0 records out Jun 02 21:43:51 volumio volumio[1295]: 0 bytes copied, 0.000230428 s, 0.0 kB/s Jun 02 21:43:52 volumio volumio[1295]: 0+0 records in Jun 02 21:43:52 volumio volumio[1295]: 0+0 records out Jun 02 21:43:52 volumio volumio[1295]: 0 bytes copied, 0.00023398 s, 0.0 kB/s Jun 02 21:43:52 volumio volumio[1295]: 0+0 records in Jun 02 21:43:52 volumio volumio[1295]: 0+0 records out Jun 02 21:43:52 volumio volumio[1295]: 0 bytes copied, 0.00010519 s, 0.0 kB/s Jun 02 21:43:52 volumio volumio[1295]: 0+0 records in Jun 02 21:43:52 volumio volumio[1295]: 0+0 records out Jun 02 21:43:52 volumio volumio[1295]: 0 bytes copied, 0.000221185 s, 0.0 kB/s Jun 02 21:43:53 volumio volumio[1295]: 0+0 records in Jun 02 21:43:53 volumio volumio[1295]: 0+0 records out Jun 02 21:43:53 volumio volumio[1295]: 0 bytes copied, 9.9162e-05 s, 0.0 kB/s Jun 02 21:43:53 volumio volumio[1295]: 0+0 records in Jun 02 21:43:53 volumio volumio[1295]: 0+0 records out Jun 02 21:43:53 volumio volumio[1295]: 0 bytes copied, 0.000221686 s, 0.0 kB/s Jun 02 21:43:53 volumio volumio[1295]: 0+0 records in Jun 02 21:43:53 volumio volumio[1295]: 0+0 records out Jun 02 21:43:53 volumio volumio[1295]: 0 bytes copied, 0.000105165 s, 0.0 kB/s Jun 02 21:43:54 volumio volumio[1295]: 0+0 records in Jun 02 21:43:54 volumio volumio[1295]: 0+0 records out Jun 02 21:43:54 volumio volumio[1295]: 0 bytes copied, 9.0646e-05 s, 0.0 kB/s Jun 02 21:43:54 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jun 02 21:43:54 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jun 02 21:43:54 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 21:43:54 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:54 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jun 02 21:43:54 volumio volumio[1295]: info: CorePlayQueue::getTrack 10 Jun 02 21:43:54 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 02 21:43:54 volumio volumio[1295]: info: ControllerMpd::resume Jun 02 21:43:54 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jun 02 21:43:54 volumio volumio[1295]: info: sendMpdCommand play took 2 milliseconds Jun 02 21:43:54 volumio volumio[1295]: 0+0 records in Jun 02 21:43:54 volumio volumio[1295]: 0+0 records out Jun 02 21:43:54 volumio volumio[1295]: 0 bytes copied, 0.000232904 s, 0.0 kB/s Jun 02 21:43:54 volumio volumio[1295]: 0+0 records in Jun 02 21:43:54 volumio volumio[1295]: 0+0 records out Jun 02 21:43:54 volumio volumio[1295]: 0 bytes copied, 9.7812e-05 s, 0.0 kB/s Jun 02 21:43:55 volumio volumio[1295]: 0+0 records in Jun 02 21:43:55 volumio volumio[1295]: 0+0 records out Jun 02 21:43:55 volumio volumio[1295]: 0 bytes copied, 0.000103239 s, 0.0 kB/s Jun 02 21:43:55 volumio volumio[1295]: 0+0 records in Jun 02 21:43:55 volumio volumio[1295]: 0+0 records out Jun 02 21:43:55 volumio volumio[1295]: 0 bytes copied, 0.000282854 s, 0.0 kB/s Jun 02 21:43:55 volumio volumio[1295]: 0+0 records in Jun 02 21:43:55 volumio volumio[1295]: 0+0 records out Jun 02 21:43:55 volumio volumio[1295]: 0 bytes copied, 9.5697e-05 s, 0.0 kB/s Jun 02 21:43:56 volumio volumio[1295]: 0+0 records in Jun 02 21:43:56 volumio volumio[1295]: 0+0 records out Jun 02 21:43:56 volumio volumio[1295]: 0 bytes copied, 0.000614357 s, 0.0 kB/s Jun 02 21:43:56 volumio volumio[1295]: 0+0 records in Jun 02 21:43:56 volumio volumio[1295]: 0+0 records out Jun 02 21:43:56 volumio volumio[1295]: 0 bytes copied, 0.000291684 s, 0.0 kB/s Jun 02 21:43:56 volumio volumio[1295]: 0+0 records in Jun 02 21:43:56 volumio volumio[1295]: 0+0 records out Jun 02 21:43:56 volumio volumio[1295]: 0 bytes copied, 0.000233592 s, 0.0 kB/s Jun 02 21:43:57 volumio volumio[1295]: 0+0 records in Jun 02 21:43:57 volumio volumio[1295]: 0+0 records out Jun 02 21:43:57 volumio volumio[1295]: 0 bytes copied, 0.000297123 s, 0.0 kB/s Jun 02 21:43:57 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 02 21:43:57 volumio volumio[1295]: info: CoreCommandRouter::volumioGetQueue Jun 02 21:43:57 volumio volumio[1295]: info: CoreStateMachine::getQueue Jun 02 21:43:57 volumio volumio[1295]: info: CorePlayQueue::getQueue Jun 02 21:43:57 volumio volumio[1295]: info: Preload queue cleared Jun 02 21:43:57 volumio volumio[1295]: 0+0 records in Jun 02 21:43:57 volumio volumio[1295]: 0+0 records out Jun 02 21:43:57 volumio volumio[1295]: 0 bytes copied, 0.000314345 s, 0.0 kB/s Jun 02 21:43:57 volumio volumio[1295]: 0+0 records in Jun 02 21:43:57 volumio volumio[1295]: 0+0 records out Jun 02 21:43:57 volumio volumio[1295]: 0 bytes copied, 0.000245135 s, 0.0 kB/s Jun 02 21:43:58 volumio volumio[1295]: 0+0 records in Jun 02 21:43:58 volumio volumio[1295]: 0+0 records out Jun 02 21:43:58 volumio volumio[1295]: 0 bytes copied, 0.000312268 s, 0.0 kB/s Jun 02 21:43:58 volumio volumio[1295]: 0+0 records in Jun 02 21:43:58 volumio volumio[1295]: 0+0 records out Jun 02 21:43:58 volumio volumio[1295]: 0 bytes copied, 0.000150913 s, 0.0 kB/s Jun 02 21:43:58 volumio volumio[1295]: 0+0 records in Jun 02 21:43:58 volumio volumio[1295]: 0+0 records out Jun 02 21:43:58 volumio volumio[1295]: 0 bytes copied, 0.000128777 s, 0.0 kB/s Jun 02 21:43:59 volumio volumio[1295]: 0+0 records in Jun 02 21:43:59 volumio volumio[1295]: 0+0 records out Jun 02 21:43:59 volumio volumio[1295]: 0 bytes copied, 0.000323449 s, 0.0 kB/s Jun 02 21:43:59 volumio volumio[1295]: 0+0 records in Jun 02 21:43:59 volumio volumio[1295]: 0+0 records out Jun 02 21:43:59 volumio volumio[1295]: 0 bytes copied, 9.1233e-05 s, 0.0 kB/s Jun 02 21:43:59 volumio volumio[1295]: 0+0 records in Jun 02 21:43:59 volumio volumio[1295]: 0+0 records out Jun 02 21:43:59 volumio volumio[1295]: 0 bytes copied, 9.6699e-05 s, 0.0 kB/s Jun 02 21:43:59 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 02 21:44:00 volumio volumio[1295]: Use XMLElement.setAttribute instead Jun 02 21:44:00 volumio volumio[1295]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 21:44:00 volumio volumio[1295]: TypeError: children[i].attr(...).value is not a function Jun 02 21:44:00 volumio volumio[1295]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Jun 02 21:44:00 volumio volumio[1295]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 21:44:00 volumio volumio[1295]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 21:44:00 volumio sudo[9256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-02 21:43' Jun 02 21:44:00 volumio sudo[9256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:47:19 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f0f6a90610a0a39b2758a5b3958219e6"