-- Logs begin at Tue 2025-05-13 20:29:17 MST, end at Tue 2025-05-13 23:45:49 MST. -- May 13 23:44:00 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:00 volumio volumio[1083]: 108+0 records in May 13 23:44:00 volumio volumio[1083]: 108+0 records out May 13 23:44:00 volumio volumio[1083]: 3538944 bytes (3.5 MB, 3.4 MiB) copied, 0.00889591 s, 398 MB/s May 13 23:44:01 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:01 volumio volumio[1083]: 2+0 records in May 13 23:44:01 volumio volumio[1083]: 2+0 records out May 13 23:44:01 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000141759 s, 462 MB/s May 13 23:44:02 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:02 volumio volumio[1083]: 110+0 records in May 13 23:44:02 volumio volumio[1083]: 110+0 records out May 13 23:44:02 volumio volumio[1083]: 3604480 bytes (3.6 MB, 3.4 MiB) copied, 0.00872973 s, 413 MB/s May 13 23:44:03 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:03 volumio volumio[1083]: 104+0 records in May 13 23:44:03 volumio volumio[1083]: 104+0 records out May 13 23:44:03 volumio volumio[1083]: 3407872 bytes (3.4 MB, 3.2 MiB) copied, 0.00789326 s, 432 MB/s May 13 23:44:04 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:04 volumio volumio[1083]: 2+0 records in May 13 23:44:04 volumio volumio[1083]: 2+0 records out May 13 23:44:04 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144796 s, 453 MB/s May 13 23:44:05 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:05 volumio volumio[1083]: 2+0 records in May 13 23:44:05 volumio volumio[1083]: 2+0 records out May 13 23:44:05 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000158518 s, 413 MB/s May 13 23:44:06 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:06 volumio volumio[1083]: 2+0 records in May 13 23:44:06 volumio volumio[1083]: 2+0 records out May 13 23:44:06 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000133759 s, 490 MB/s May 13 23:44:07 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:07 volumio volumio[1083]: 2+0 records in May 13 23:44:07 volumio volumio[1083]: 2+0 records out May 13 23:44:07 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000142981 s, 458 MB/s May 13 23:44:08 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:08 volumio volumio[1083]: 2+0 records in May 13 23:44:08 volumio volumio[1083]: 2+0 records out May 13 23:44:08 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000139426 s, 470 MB/s May 13 23:44:09 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:09 volumio volumio[1083]: 2+0 records in May 13 23:44:09 volumio volumio[1083]: 2+0 records out May 13 23:44:09 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.00014624 s, 448 MB/s May 13 23:44:10 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:10 volumio volumio[1083]: 2+0 records in May 13 23:44:10 volumio volumio[1083]: 2+0 records out May 13 23:44:10 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144167 s, 455 MB/s May 13 23:44:11 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:11 volumio volumio[1083]: 2+0 records in May 13 23:44:11 volumio volumio[1083]: 2+0 records out May 13 23:44:11 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000151796 s, 432 MB/s May 13 23:44:12 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:12 volumio volumio[1083]: 112+0 records in May 13 23:44:12 volumio volumio[1083]: 112+0 records out May 13 23:44:12 volumio volumio[1083]: 3670016 bytes (3.7 MB, 3.5 MiB) copied, 0.00884073 s, 415 MB/s May 13 23:44:13 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:13 volumio volumio[1083]: 116+0 records in May 13 23:44:13 volumio volumio[1083]: 116+0 records out May 13 23:44:13 volumio volumio[1083]: 3801088 bytes (3.8 MB, 3.6 MiB) copied, 0.00917254 s, 414 MB/s May 13 23:44:14 volumio volumio[1083]: info: VolumeController::SetAlsaVolume100 May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState May 13 23:44:14 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:14 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:14 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:14 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:14 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: SPOTIFY VOLUME 69 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: VOLUMIO VOLUME 100 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 23:44:14 volumio volumio[1083]: info: Setting Spotify Volume from Volumio: 100 May 13 23:44:14 volumio volumio[1083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 13 23:44:14 volumio sudo[8104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:14 volumio sudo[8104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:14 volumio sudo[8104]: pam_unix(sudo:session): session closed for user root May 13 23:44:14 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:14 volumio volumio[1083]: info: VolumeController::SetAlsaVolume16 May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState May 13 23:44:14 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:14 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:14 volumio volumio[1083]: 2+0 records in May 13 23:44:14 volumio volumio[1083]: 2+0 records out May 13 23:44:14 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000134815 s, 486 MB/s May 13 23:44:14 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:14 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:14 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:14 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:14 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 16 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: SPOTIFY VOLUME 100 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: VOLUMIO VOLUME 16 May 13 23:44:14 volumio volumio[1083]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 23:44:14 volumio volumio[1083]: info: Setting Spotify Volume from Volumio: 16 May 13 23:44:14 volumio sudo[8113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:14 volumio sudo[8113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:14 volumio volumio[1083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 13 23:44:14 volumio sudo[8113]: pam_unix(sudo:session): session closed for user root May 13 23:44:14 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:15 volumio volumio[1083]: info: VolumeController::SetAlsaVolume66 May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState May 13 23:44:15 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:15 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:15 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:15 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:15 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: SPOTIFY VOLUME 16 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: VOLUMIO VOLUME 66 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 23:44:15 volumio volumio[1083]: info: Setting Spotify Volume from Volumio: 66 May 13 23:44:15 volumio volumio[1083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 13 23:44:15 volumio sudo[8119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:15 volumio sudo[8119]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:15 volumio sudo[8119]: pam_unix(sudo:session): session closed for user root May 13 23:44:15 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:15 volumio volumio[1083]: info: VolumeController::SetAlsaVolume68 May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState May 13 23:44:15 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:15 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:15 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:15 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:15 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:15 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: SPOTIFY VOLUME 66 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: VOLUMIO VOLUME 68 May 13 23:44:15 volumio volumio[1083]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 23:44:15 volumio volumio[1083]: info: Setting Spotify Volume from Volumio: 68 May 13 23:44:15 volumio sudo[8123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:15 volumio sudo[8123]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:15 volumio volumio[1083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 13 23:44:15 volumio sudo[8123]: pam_unix(sudo:session): session closed for user root May 13 23:44:15 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:15 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:15 volumio volumio[1083]: 2+0 records in May 13 23:44:15 volumio volumio[1083]: 2+0 records out May 13 23:44:15 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000156222 s, 420 MB/s May 13 23:44:16 volumio volumio[1083]: SPOTIFY: SETTING SPOTIFY VOLUME 68 May 13 23:44:16 volumio volumio[1083]: info: Sending Spotify command with payload to local API: /player/volume May 13 23:44:16 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:16 volumio volumio[1083]: 2+0 records in May 13 23:44:16 volumio volumio[1083]: 2+0 records out May 13 23:44:16 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000172148 s, 381 MB/s May 13 23:44:18 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:18 volumio volumio[1083]: 2+0 records in May 13 23:44:18 volumio volumio[1083]: 2+0 records out May 13 23:44:18 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000149666 s, 438 MB/s May 13 23:44:19 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:19 volumio volumio[1083]: 96+0 records in May 13 23:44:19 volumio volumio[1083]: 96+0 records out May 13 23:44:19 volumio volumio[1083]: 3145728 bytes (3.1 MB, 3.0 MiB) copied, 0.00747775 s, 421 MB/s May 13 23:44:20 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:20 volumio volumio[1083]: 2+1 records in May 13 23:44:20 volumio volumio[1083]: 2+1 records out May 13 23:44:20 volumio volumio[1083]: 69632 bytes (70 kB, 68 KiB) copied, 0.000209574 s, 332 MB/s May 13 23:44:21 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:21 volumio volumio[1083]: 2+0 records in May 13 23:44:21 volumio volumio[1083]: 2+0 records out May 13 23:44:21 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000142333 s, 460 MB/s May 13 23:44:21 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::ClearQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::stPlaybackTimer May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 1 May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::serviceStop May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 1 May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::serviceStop May 13 23:44:21 volumio volumio[1083]: info: [1747205061138] ControllerUPNPBrowser::stop May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::clearPlayQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::addQueueItems May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::addQueueItems May 13 23:44:21 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4753 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4753 May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::play index 0 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::addQueueItems May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::addQueueItems May 13 23:44:21 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4756 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4756 May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4755 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4755 May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4754 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4754 May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4758 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4758 May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4759 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4759 May 13 23:44:21 volumio volumio[1083]: info: Adding Item to queue: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4757 May 13 23:44:21 volumio volumio[1083]: info: Using cached record of: upnp/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190-4757 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::play index undefined May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:21 volumio volumio[1083]: info: [1747205061156] ControllerUPNPBrowser::clearAddPlayTrack May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop May 13 23:44:21 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:44:21 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand stop took 44 milliseconds May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand stop took 26 milliseconds May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand clear May 13 23:44:21 volumio sudo[8182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:44:21 volumio sudo[8182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:21 volumio systemd[1]: Stopping peppyspectrum Daemon... May 13 23:44:21 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:21 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand status took 16 milliseconds May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand clear took 16 milliseconds May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" May 13 23:44:21 volumio volumio[1083]: error: updateQueue error: null May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 2ms May 13 23:44:21 volumio volumio[1083]: info: touch_display: Setting screensaver timeout to 120 seconds. May 13 23:44:21 volumio systemd[1]: peppyspectrum.service: Succeeded. May 13 23:44:21 volumio systemd[1]: Stopped peppyspectrum Daemon. May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 18 milliseconds May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:21 volumio volumio[1083]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 36ms May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:21 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand add "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" took 1 milliseconds May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand play May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 1ms May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand play took 2 milliseconds May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:21 volumio sudo[8182]: pam_unix(sudo:session): session closed for user root May 13 23:44:21 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:21 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand status took 14 milliseconds May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:21 volumio kernel: usb usb1-port1: over-current change #67 May 13 23:44:21 volumio kernel: usb usb3-port1: over-current change #82 May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:21 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:21 volumio volumio[1083]: info: May 13 23:44:21 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:21 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:21 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:21 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:21 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand status took 4 milliseconds May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 3ms May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand status took 3 milliseconds May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:21 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:21 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 19ms May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds May 13 23:44:21 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:21 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:21 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":611,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4271 Kbps","isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:21 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play May 13 23:44:21 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:21 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:21 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":679,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4311 Kbps","isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:21 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play May 13 23:44:21 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 20ms May 13 23:44:21 volumio volumio[1083]: info: ------------------------------ 19ms May 13 23:44:21 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:21 volumio kernel: usb usb4-port1: over-current change #84 May 13 23:44:21 volumio kernel: usb usb2-port1: over-current change #84 May 13 23:44:21 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:21 volumio sudo[8192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:21 volumio sudo[8192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:21 volumio sudo[8195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:21 volumio sudo[8195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:21 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:21 volumio systemd[1]: Started peppyspectrum Daemon. May 13 23:44:21 volumio sudo[8199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:21 volumio sudo[8199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:21 volumio sudo[8192]: pam_unix(sudo:session): session closed for user root May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:21 volumio sudo[8199]: pam_unix(sudo:session): session closed for user root May 13 23:44:21 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:21 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:21 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:21 volumio sudo[8195]: pam_unix(sudo:session): session closed for user root May 13 23:44:21 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:21 volumio sudo[8203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:21 volumio sudo[8203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:21 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:21 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:21 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:21 volumio sudo[8203]: pam_unix(sudo:session): session closed for user root May 13 23:44:21 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:21 volumio kernel: usb usb1-port2: over-current change #65 May 13 23:44:21 volumio kernel: usb usb3-port2: over-current change #65 May 13 23:44:21 volumio kernel: usb usb2-port1: over-current change #85 May 13 23:44:21 volumio kernel: usb usb4-port1: over-current change #85 May 13 23:44:21 volumio kernel: usb 1-2: USB disconnect, device number 47 May 13 23:44:21 volumio kernel: usb usb3-port1: over-current change #83 May 13 23:44:21 volumio kernel: usb usb3-port2: over-current change #66 May 13 23:44:21 volumio kernel: usb usb2-port1: over-current change #86 May 13 23:44:21 volumio kernel: usb usb4-port1: over-current change #86 May 13 23:44:21 volumio volumio[8196]: libpng warning: iCCP: known incorrect sRGB profile May 13 23:44:21 volumio kernel: usb usb3-port1: over-current change #84 May 13 23:44:21 volumio kernel: usb usb1-port1: over-current change #68 May 13 23:44:22 volumio kernel: usb usb1-port2: over-current change #66 May 13 23:44:22 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:22 volumio volumio[1083]: 109+0 records in May 13 23:44:22 volumio volumio[1083]: 109+0 records out May 13 23:44:22 volumio volumio[1083]: 3571712 bytes (3.6 MB, 3.4 MiB) copied, 0.00824338 s, 433 MB/s May 13 23:44:22 volumio kernel: usb 1-2: new full-speed USB device number 48 using xhci-hcd May 13 23:44:22 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 13 23:44:22 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 13 23:44:22 volumio kernel: usb 1-2: Product: USB Receiver May 13 23:44:22 volumio kernel: usb 1-2: Manufacturer: Logitech May 13 23:44:22 volumio kernel: logitech-djreceiver 0003:046D:C52B.00AF: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 13 23:44:22 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.00AF/0003:046D:404D.00B0/input/input58 May 13 23:44:22 volumio kernel: logitech-hidpp-device 0003:046D:404D.00B0: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 13 23:44:22 volumio systemd-udevd[8206]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:23 volumio systemd-udevd[8207]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:23 volumio systemd-logind[822]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) May 13 23:44:23 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:23 volumio volumio[1083]: 2+0 records in May 13 23:44:23 volumio volumio[1083]: 2+0 records out May 13 23:44:23 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000154389 s, 424 MB/s May 13 23:44:23 volumio kernel: usb usb1-port1: over-current change #69 May 13 23:44:23 volumio kernel: usb usb3-port1: over-current change #85 May 13 23:44:23 volumio kernel: usb usb2-port1: over-current change #87 May 13 23:44:23 volumio kernel: usb usb4-port1: over-current change #87 May 13 23:44:23 volumio kernel: usb usb1-port2: over-current change #67 May 13 23:44:23 volumio kernel: usb usb3-port2: over-current change #67 May 13 23:44:23 volumio kernel: usb 1-2: USB disconnect, device number 48 May 13 23:44:24 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:24 volumio volumio[1083]: 110+0 records in May 13 23:44:24 volumio volumio[1083]: 110+0 records out May 13 23:44:24 volumio volumio[1083]: 3604480 bytes (3.6 MB, 3.4 MiB) copied, 0.00851382 s, 423 MB/s May 13 23:44:24 volumio kernel: usb 1-2: new full-speed USB device number 49 using xhci-hcd May 13 23:44:24 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 13 23:44:24 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 13 23:44:24 volumio kernel: usb 1-2: Product: USB Receiver May 13 23:44:24 volumio kernel: usb 1-2: Manufacturer: Logitech May 13 23:44:24 volumio kernel: logitech-djreceiver 0003:046D:C52B.00B3: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 13 23:44:24 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.00B3/0003:046D:404D.00B4/input/input59 May 13 23:44:24 volumio kernel: logitech-hidpp-device 0003:046D:404D.00B4: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 13 23:44:24 volumio systemd-udevd[8206]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:24 volumio systemd-udevd[8207]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:24 volumio systemd-logind[822]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) May 13 23:44:25 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:25 volumio volumio[1083]: 2+0 records in May 13 23:44:25 volumio volumio[1083]: 2+0 records out May 13 23:44:25 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001495 s, 438 MB/s May 13 23:44:26 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:26 volumio volumio[1083]: 2+0 records in May 13 23:44:26 volumio volumio[1083]: 2+0 records out May 13 23:44:26 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000148778 s, 440 MB/s May 13 23:44:27 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:27 volumio volumio[1083]: 2+0 records in May 13 23:44:27 volumio volumio[1083]: 2+0 records out May 13 23:44:27 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000142129 s, 461 MB/s May 13 23:44:28 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:28 volumio volumio[1083]: 2+0 records in May 13 23:44:28 volumio volumio[1083]: 2+0 records out May 13 23:44:28 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000152648 s, 429 MB/s May 13 23:44:29 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:29 volumio volumio[1083]: 2+0 records in May 13 23:44:29 volumio volumio[1083]: 2+0 records out May 13 23:44:29 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000148777 s, 440 MB/s May 13 23:44:30 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:30 volumio volumio[1083]: 2+0 records in May 13 23:44:30 volumio volumio[1083]: 2+0 records out May 13 23:44:30 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144926 s, 452 MB/s May 13 23:44:30 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::ClearQueue May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::stPlaybackTimer May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::serviceStop May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::serviceStop May 13 23:44:30 volumio volumio[1083]: info: [1747205070743] ControllerUPNPBrowser::stop May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::clearPlayQueue May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::addQueueItems May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::addQueueItems May 13 23:44:30 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:30 volumio volumio[1083]: info: Adding Item to queue: upnp/folder/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190 May 13 23:44:30 volumio volumio[1083]: info: Exploding uri upnp/folder/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_1012be52_e1b6a190 in service upnp_browser May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:44:30 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand stop took 31 milliseconds May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:30 volumio sudo[8303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:44:30 volumio sudo[8303]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:30 volumio systemd[1]: Stopping peppyspectrum Daemon... May 13 23:44:30 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand status took 16 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:30 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: No code May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 37ms May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:44:30 volumio sudo[8308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:44:30 volumio sudo[8308]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:30 volumio kernel: usb usb1-port1: over-current change #70 May 13 23:44:30 volumio kernel: usb usb3-port1: over-current change #86 May 13 23:44:30 volumio systemd[1]: peppyspectrum.service: Succeeded. May 13 23:44:30 volumio systemd[1]: Stopped peppyspectrum Daemon. May 13 23:44:30 volumio sudo[8313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:44:30 volumio sudo[8313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:30 volumio sudo[8308]: pam_unix(sudo:session): session closed for user root May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:44:30 volumio sudo[8303]: pam_unix(sudo:session): session closed for user root May 13 23:44:30 volumio kernel: usb usb2-port1: over-current change #88 May 13 23:44:30 volumio kernel: usb usb4-port1: over-current change #88 May 13 23:44:30 volumio sudo[8313]: pam_unix(sudo:session): session closed for user root May 13 23:44:30 volumio sudo[8318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:44:30 volumio sudo[8318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:44:30 volumio sudo[8318]: pam_unix(sudo:session): session closed for user root May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::play index 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::play index undefined May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: info: [1747205070911] ControllerUPNPBrowser::clearAddPlayTrack May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop May 13 23:44:30 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:30 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:30 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:30 volumio volumio[1083]: info: touch_display: Setting screensaver timeout to 120 seconds. May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand stop took 8 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand clear May 13 23:44:30 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:44:30 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:44:30 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:44:30 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:30 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand clear took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" May 13 23:44:30 volumio volumio[1083]: error: updateQueue error: null May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 1ms May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:30 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand add "http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM" took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand play May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 0ms May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand play took 0 milliseconds May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - ---- read samplerate from file: 192000 May 13 23:44:30 volumio volumio[1083]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand status took 13 milliseconds May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces system playlist update May 13 23:44:30 volumio volumio[1083]: info: Ignoring MPD Status Update May 13 23:44:30 volumio volumio[1083]: info: May 13 23:44:30 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:30 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 14ms May 13 23:44:30 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:30 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:30 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:30 volumio volumio[1083]: error: FusionDsp - WebSocket error: [object Object] May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 2ms May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":679,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4678 Kbps","isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:30 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play May 13 23:44:30 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 10ms May 13 23:44:30 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 8 milliseconds May 13 23:44:30 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:44:30 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:44:30 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:44:30 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":679,"duration":353,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4678 Kbps","isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:44:30 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play May 13 23:44:30 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:44:30 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:44:30 volumio volumio[1083]: info: ------------------------------ 18ms May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:30 volumio kernel: usb usb1-port2: over-current change #68 May 13 23:44:30 volumio kernel: usb usb3-port2: over-current change #68 May 13 23:44:30 volumio sudo[8322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:30 volumio sudo[8322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:30 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:30 volumio sudo[8325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:30 volumio sudo[8325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:31 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status play May 13 23:44:31 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:31 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:31 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:31 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:31 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:31 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:31 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:31 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:31 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:31 volumio volumio[1083]: info: CoreCommandRouter::volumioGetQueue May 13 23:44:31 volumio volumio[1083]: info: CoreStateMachine::getQueue May 13 23:44:31 volumio volumio[1083]: info: CorePlayQueue::getQueue May 13 23:44:31 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:44:31 volumio sudo[8329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:31 volumio sudo[8329]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:31 volumio systemd[1]: Started peppyspectrum Daemon. May 13 23:44:31 volumio sudo[8332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 13 23:44:31 volumio sudo[8332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:31 volumio sudo[8322]: pam_unix(sudo:session): session closed for user root May 13 23:44:31 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:31 volumio sudo[8332]: pam_unix(sudo:session): session closed for user root May 13 23:44:31 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:31 volumio sudo[8325]: pam_unix(sudo:session): session closed for user root May 13 23:44:31 volumio sudo[8329]: pam_unix(sudo:session): session closed for user root May 13 23:44:31 volumio volumio[1083]: info: peppyspectrum Daemon Started May 13 23:44:31 volumio kernel: usb usb2-port1: over-current change #89 May 13 23:44:31 volumio kernel: usb usb4-port1: over-current change #89 May 13 23:44:31 volumio kernel: usb 1-2: USB disconnect, device number 49 May 13 23:44:31 volumio kernel: usb usb3-port1: over-current change #87 May 13 23:44:31 volumio kernel: usb usb3-port2: over-current change #69 May 13 23:44:31 volumio kernel: usb usb4-port1: over-current change #90 May 13 23:44:31 volumio kernel: usb usb2-port1: over-current change #90 May 13 23:44:31 volumio volumio[8326]: libpng warning: iCCP: known incorrect sRGB profile May 13 23:44:31 volumio kernel: usb usb3-port1: over-current change #88 May 13 23:44:31 volumio kernel: usb usb1-port1: over-current change #71 May 13 23:44:31 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:31 volumio volumio[1083]: 2+0 records in May 13 23:44:31 volumio volumio[1083]: 2+0 records out May 13 23:44:31 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000204074 s, 321 MB/s May 13 23:44:31 volumio kernel: usb usb1-port2: over-current change #69 May 13 23:44:32 volumio kernel: usb 1-2: new full-speed USB device number 50 using xhci-hcd May 13 23:44:32 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 13 23:44:32 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 13 23:44:32 volumio kernel: usb 1-2: Product: USB Receiver May 13 23:44:32 volumio kernel: usb 1-2: Manufacturer: Logitech May 13 23:44:32 volumio kernel: logitech-djreceiver 0003:046D:C52B.00B7: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 13 23:44:32 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.00B7/0003:046D:404D.00B8/input/input60 May 13 23:44:32 volumio kernel: logitech-hidpp-device 0003:046D:404D.00B8: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 13 23:44:32 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:32 volumio volumio[1083]: 2+0 records in May 13 23:44:32 volumio volumio[1083]: 2+0 records out May 13 23:44:32 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000152018 s, 431 MB/s May 13 23:44:32 volumio systemd-udevd[8317]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:32 volumio systemd-udevd[8311]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:32 volumio systemd-logind[822]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) May 13 23:44:33 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:33 volumio volumio[1083]: 2+0 records in May 13 23:44:33 volumio volumio[1083]: 2+0 records out May 13 23:44:33 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000152888 s, 429 MB/s May 13 23:44:34 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:34 volumio volumio[1083]: 2+0 records in May 13 23:44:34 volumio volumio[1083]: 2+0 records out May 13 23:44:34 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000154981 s, 423 MB/s May 13 23:44:35 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:44:35 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:35 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:35 volumio volumio[1083]: 2+0 records in May 13 23:44:35 volumio volumio[1083]: 2+0 records out May 13 23:44:35 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000170944 s, 383 MB/s May 13 23:44:36 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:36 volumio volumio[1083]: 2+0 records in May 13 23:44:36 volumio volumio[1083]: 2+0 records out May 13 23:44:36 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.00015537 s, 422 MB/s May 13 23:44:37 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:37 volumio volumio[1083]: 2+0 records in May 13 23:44:37 volumio volumio[1083]: 2+0 records out May 13 23:44:37 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000152981 s, 428 MB/s May 13 23:44:38 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:38 volumio volumio[1083]: 18+128 records in May 13 23:44:38 volumio volumio[1083]: 18+128 records out May 13 23:44:38 volumio volumio[1083]: 1114016 bytes (1.1 MB, 1.1 MiB) copied, 0.00263975 s, 422 MB/s May 13 23:44:39 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:39 volumio volumio[1083]: 29+1 records in May 13 23:44:39 volumio volumio[1083]: 29+1 records out May 13 23:44:39 volumio volumio[1083]: 979040 bytes (979 kB, 956 KiB) copied, 0.00270146 s, 362 MB/s May 13 23:44:40 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:40 volumio volumio[1083]: 128+0 records in May 13 23:44:40 volumio volumio[1083]: 128+0 records out May 13 23:44:40 volumio volumio[1083]: 4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.010547 s, 398 MB/s May 13 23:44:41 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:41 volumio volumio[1083]: 124+0 records in May 13 23:44:41 volumio volumio[1083]: 124+0 records out May 13 23:44:41 volumio volumio[1083]: 4063232 bytes (4.1 MB, 3.9 MiB) copied, 0.00986169 s, 412 MB/s May 13 23:44:42 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:42 volumio volumio[1083]: 128+0 records in May 13 23:44:42 volumio volumio[1083]: 128+0 records out May 13 23:44:42 volumio volumio[1083]: 4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.0103346 s, 406 MB/s May 13 23:44:43 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:44:43 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:44 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:44 volumio volumio[1083]: 80+0 records in May 13 23:44:44 volumio volumio[1083]: 80+0 records out May 13 23:44:44 volumio volumio[1083]: 2621440 bytes (2.6 MB, 2.5 MiB) copied, 0.00666667 s, 393 MB/s May 13 23:44:45 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:45 volumio volumio[1083]: 2+0 records in May 13 23:44:45 volumio volumio[1083]: 2+0 records out May 13 23:44:45 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000160352 s, 409 MB/s May 13 23:44:46 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:46 volumio volumio[1083]: 2+0 records in May 13 23:44:46 volumio volumio[1083]: 2+0 records out May 13 23:44:46 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000168556 s, 389 MB/s May 13 23:44:47 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:47 volumio volumio[1083]: 2+0 records in May 13 23:44:47 volumio volumio[1083]: 2+0 records out May 13 23:44:47 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.00014324 s, 458 MB/s May 13 23:44:48 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:48 volumio volumio[1083]: 2+0 records in May 13 23:44:48 volumio volumio[1083]: 2+0 records out May 13 23:44:48 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000162203 s, 404 MB/s May 13 23:44:49 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:49 volumio volumio[1083]: 2+0 records in May 13 23:44:49 volumio volumio[1083]: 2+0 records out May 13 23:44:49 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000146925 s, 446 MB/s May 13 23:44:49 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:44:50 volumio volumio[1083]: info: Preload queue cleared May 13 23:44:50 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:50 volumio volumio[1083]: 2+0 records in May 13 23:44:50 volumio volumio[1083]: 2+0 records out May 13 23:44:50 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001635 s, 401 MB/s May 13 23:44:51 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:51 volumio volumio[1083]: 2+0 records in May 13 23:44:51 volumio volumio[1083]: 2+0 records out May 13 23:44:51 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000163944 s, 400 MB/s May 13 23:44:52 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:52 volumio volumio[1083]: 86+0 records in May 13 23:44:52 volumio volumio[1083]: 86+0 records out May 13 23:44:52 volumio volumio[1083]: 2818048 bytes (2.8 MB, 2.7 MiB) copied, 0.00629069 s, 448 MB/s May 13 23:44:53 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:53 volumio volumio[1083]: 2+0 records in May 13 23:44:53 volumio volumio[1083]: 2+0 records out May 13 23:44:53 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000157629 s, 416 MB/s May 13 23:44:54 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:54 volumio volumio[1083]: 2+0 records in May 13 23:44:54 volumio volumio[1083]: 2+0 records out May 13 23:44:54 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000151222 s, 433 MB/s May 13 23:44:55 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:55 volumio volumio[1083]: 2+1 records in May 13 23:44:55 volumio volumio[1083]: 2+1 records out May 13 23:44:55 volumio volumio[1083]: 69632 bytes (70 kB, 68 KiB) copied, 0.000195573 s, 356 MB/s May 13 23:44:56 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:56 volumio volumio[1083]: 2+0 records in May 13 23:44:56 volumio volumio[1083]: 2+0 records out May 13 23:44:56 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000147555 s, 444 MB/s May 13 23:44:57 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:57 volumio volumio[1083]: 2+0 records in May 13 23:44:57 volumio volumio[1083]: 2+0 records out May 13 23:44:57 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001545 s, 424 MB/s May 13 23:44:58 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:58 volumio volumio[1083]: 2+0 records in May 13 23:44:58 volumio volumio[1083]: 2+0 records out May 13 23:44:58 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000146648 s, 447 MB/s May 13 23:44:59 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:44:59 volumio volumio[1083]: 2+0 records in May 13 23:44:59 volumio volumio[1083]: 2+0 records out May 13 23:44:59 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000154074 s, 425 MB/s May 13 23:45:00 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:00 volumio volumio[1083]: 2+0 records in May 13 23:45:00 volumio volumio[1083]: 2+0 records out May 13 23:45:00 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000150889 s, 434 MB/s May 13 23:45:01 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:01 volumio volumio[1083]: 2+0 records in May 13 23:45:01 volumio volumio[1083]: 2+0 records out May 13 23:45:01 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000143241 s, 458 MB/s May 13 23:45:02 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:02 volumio volumio[1083]: 2+0 records in May 13 23:45:02 volumio volumio[1083]: 2+0 records out May 13 23:45:02 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144407 s, 454 MB/s May 13 23:45:03 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:03 volumio volumio[1083]: 2+0 records in May 13 23:45:03 volumio volumio[1083]: 2+0 records out May 13 23:45:03 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000162129 s, 404 MB/s May 13 23:45:04 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:04 volumio volumio[1083]: 2+0 records in May 13 23:45:04 volumio volumio[1083]: 2+0 records out May 13 23:45:04 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000143315 s, 457 MB/s May 13 23:45:05 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:05 volumio volumio[1083]: 2+0 records in May 13 23:45:05 volumio volumio[1083]: 2+0 records out May 13 23:45:05 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000145241 s, 451 MB/s May 13 23:45:06 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:06 volumio volumio[1083]: 2+0 records in May 13 23:45:06 volumio volumio[1083]: 2+0 records out May 13 23:45:06 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000150722 s, 435 MB/s May 13 23:45:07 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:07 volumio volumio[1083]: 2+0 records in May 13 23:45:07 volumio volumio[1083]: 2+0 records out May 13 23:45:07 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000172444 s, 380 MB/s May 13 23:45:08 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:08 volumio volumio[1083]: 2+0 records in May 13 23:45:08 volumio volumio[1083]: 2+0 records out May 13 23:45:08 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000146351 s, 448 MB/s May 13 23:45:09 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:09 volumio volumio[1083]: 2+0 records in May 13 23:45:09 volumio volumio[1083]: 2+0 records out May 13 23:45:09 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144481 s, 454 MB/s May 13 23:45:10 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:10 volumio volumio[1083]: 2+0 records in May 13 23:45:10 volumio volumio[1083]: 2+0 records out May 13 23:45:10 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000147296 s, 445 MB/s May 13 23:45:11 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:11 volumio volumio[1083]: 2+0 records in May 13 23:45:11 volumio volumio[1083]: 2+0 records out May 13 23:45:11 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000188314 s, 348 MB/s May 13 23:45:12 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:12 volumio volumio[1083]: 2+0 records in May 13 23:45:12 volumio volumio[1083]: 2+0 records out May 13 23:45:12 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000159241 s, 412 MB/s May 13 23:45:14 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:14 volumio volumio[1083]: 55+0 records in May 13 23:45:14 volumio volumio[1083]: 55+0 records out May 13 23:45:14 volumio volumio[1083]: 1802240 bytes (1.8 MB, 1.7 MiB) copied, 0.0053411 s, 337 MB/s May 13 23:45:15 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:15 volumio volumio[1083]: 2+0 records in May 13 23:45:15 volumio volumio[1083]: 2+0 records out May 13 23:45:15 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000148037 s, 443 MB/s May 13 23:45:16 volumio volumio[1083]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable May 13 23:45:16 volumio volumio[1083]: 2+0 records in May 13 23:45:16 volumio volumio[1083]: 2+0 records out May 13 23:45:16 volumio volumio[1083]: 65536 bytes (66 kB, 64 KiB) copied, 0.000147462 s, 444 MB/s May 13 23:45:16 volumio volumio[1083]: info: Preload queue cleared May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::ClearQueue May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::stPlaybackTimer May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::serviceStop May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::serviceStop May 13 23:45:16 volumio volumio[1083]: info: [1747205116848] ControllerUPNPBrowser::stop May 13 23:45:16 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::clearPlayQueue May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::addQueueItems May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::addQueueItems May 13 23:45:16 volumio volumio[1083]: info: Preload queue cleared May 13 23:45:16 volumio volumio[1083]: info: Adding Item to queue: upnp/folder/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_bcfda42c May 13 23:45:16 volumio volumio[1083]: info: Exploding uri upnp/folder/http://192.168.0.215:2869/upnphost/udhisapi.dll?control=uuid:3cca5ce0-55a9-46c3-ad51-898152b8ab2b+urn:upnp-org:serviceId:ContentDirectory@107_bcfda42c in service upnp_browser May 13 23:45:16 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:45:16 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:45:16 volumio volumio[1083]: info: May 13 23:45:16 volumio volumio[1083]: ---------------------------- MPD announces state update: player May 13 23:45:16 volumio volumio[1083]: info: sendMpdCommand stop took 33 milliseconds May 13 23:45:16 volumio volumio[1083]: info: ControllerMpd::getState May 13 23:45:16 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status May 13 23:45:16 volumio sudo[8770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:45:16 volumio sudo[8770]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:45:16 volumio systemd[1]: Stopping peppyspectrum Daemon... May 13 23:45:16 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM May 13 23:45:16 volumio volumio[1083]: info: sendMpdCommand status took 22 milliseconds May 13 23:45:16 volumio volumio[1083]: verbose: ControllerMpd::parseState May 13 23:45:16 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 23:45:16 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 3 milliseconds May 13 23:45:16 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo May 13 23:45:16 volumio volumio[1083]: info: ControllerMpd::pushState May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::servicePushState May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:45:16 volumio systemd[1]: peppyspectrum.service: Succeeded. May 13 23:45:16 volumio systemd[1]: Stopped peppyspectrum Daemon. May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Babylon Sisters","artist":"Steely Dan","album":"Gaucho","uri":"http://192.168.0.215:10243/WMPNSSv4/2930751917/0_MTA3XzEwMTJiZTUyX2UxYjZhMTkwLTQ3NTM","trackType":""} May 13 23:45:16 volumio volumio[1083]: verbose: CURRENT POSITION 0 May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:45:16 volumio volumio[1083]: info: No code May 13 23:45:16 volumio volumio[1083]: info: CoreStateMachine::pushState May 13 23:45:16 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:16 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState May 13 23:45:16 volumio sudo[8770]: pam_unix(sudo:session): session closed for user root May 13 23:45:16 volumio volumio[1083]: info: ------------------------------ 45ms May 13 23:45:16 volumio volumio[1083]: info: touch_display: Setting screensaver timeout to 120 seconds. May 13 23:45:16 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:45:16 volumio kernel: usb usb1-port1: over-current change #72 May 13 23:45:16 volumio kernel: usb usb3-port1: over-current change #89 May 13 23:45:16 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:45:16 volumio sudo[8777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:45:16 volumio sudo[8777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:45:16 volumio kernel: usb usb2-port1: over-current change #91 May 13 23:45:16 volumio kernel: usb usb4-port1: over-current change #91 May 13 23:45:16 volumio sudo[8777]: pam_unix(sudo:session): session closed for user root May 13 23:45:16 volumio volumio[1083]: info: PeppySpectrum ---peppyspectrum status stop May 13 23:45:16 volumio sudo[8782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:45:16 volumio sudo[8782]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:45:16 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:45:16 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:45:16 volumio sudo[8782]: pam_unix(sudo:session): session closed for user root May 13 23:45:16 volumio volumio[1083]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 13 23:45:16 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:45:17 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue May 13 23:45:17 volumio sudo[8785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 13 23:45:17 volumio sudo[8785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:45:17 volumio volumio[1083]: info: CorePlayQueue::saveQueue May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock May 13 23:45:17 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock May 13 23:45:17 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::play index 0 May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::stop May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::play index undefined May 13 23:45:17 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:45:17 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:17 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:45:17 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:45:17 volumio sudo[8785]: pam_unix(sudo:session): session closed for user root May 13 23:45:17 volumio volumio[1083]: info: peppyspectrum Daemon Stop May 13 23:45:17 volumio kernel: usb usb1-port2: over-current change #70 May 13 23:45:17 volumio kernel: usb usb3-port2: over-current change #70 May 13 23:45:17 volumio volumio[1083]: 0+0 records in May 13 23:45:17 volumio volumio[1083]: 0+0 records out May 13 23:45:17 volumio volumio[1083]: 0 bytes copied, 4.5667e-05 s, 0.0 kB/s May 13 23:45:17 volumio kernel: usb 1-2: USB disconnect, device number 50 May 13 23:45:17 volumio kernel: usb 1-2: new full-speed USB device number 51 using xhci-hcd May 13 23:45:17 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 13 23:45:18 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 13 23:45:18 volumio kernel: usb 1-2: Product: USB Receiver May 13 23:45:18 volumio kernel: usb 1-2: Manufacturer: Logitech May 13 23:45:18 volumio kernel: logitech-djreceiver 0003:046D:C52B.00BB: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 13 23:45:18 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.00BB/0003:046D:404D.00BC/input/input61 May 13 23:45:18 volumio volumio[1083]: 0+0 records in May 13 23:45:18 volumio volumio[1083]: 0+0 records out May 13 23:45:18 volumio volumio[1083]: 0 bytes copied, 4.9481e-05 s, 0.0 kB/s May 13 23:45:18 volumio kernel: logitech-hidpp-device 0003:046D:404D.00BC: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 13 23:45:18 volumio systemd-udevd[8780]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:45:18 volumio systemd-udevd[8804]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:45:18 volumio systemd-logind[822]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) May 13 23:45:19 volumio volumio[1083]: 0+0 records in May 13 23:45:19 volumio volumio[1083]: 0+0 records out May 13 23:45:19 volumio volumio[1083]: 0 bytes copied, 4.6426e-05 s, 0.0 kB/s May 13 23:45:20 volumio volumio[1083]: 0+0 records in May 13 23:45:20 volumio volumio[1083]: 0+0 records out May 13 23:45:20 volumio volumio[1083]: 0 bytes copied, 4.8574e-05 s, 0.0 kB/s May 13 23:45:20 volumio kernel: usb usb1-port1: over-current change #73 May 13 23:45:20 volumio kernel: usb usb3-port1: over-current change #90 May 13 23:45:20 volumio kernel: usb usb2-port1: over-current change #92 May 13 23:45:20 volumio kernel: usb usb4-port1: over-current change #92 May 13 23:45:20 volumio kernel: usb usb1-port2: over-current change #71 May 13 23:45:20 volumio kernel: usb usb3-port2: over-current change #71 May 13 23:45:21 volumio kernel: usb 1-2: USB disconnect, device number 51 May 13 23:45:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay May 13 23:45:21 volumio volumio[1083]: info: CoreStateMachine::play index undefined May 13 23:45:21 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 23:45:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 May 13 23:45:21 volumio volumio[1083]: 0+0 records in May 13 23:45:21 volumio volumio[1083]: 0+0 records out May 13 23:45:21 volumio volumio[1083]: 0 bytes copied, 4.6778e-05 s, 0.0 kB/s May 13 23:45:21 volumio kernel: usb 1-2: new full-speed USB device number 52 using xhci-hcd May 13 23:45:21 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 13 23:45:21 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 13 23:45:21 volumio kernel: usb 1-2: Product: USB Receiver May 13 23:45:21 volumio kernel: usb 1-2: Manufacturer: Logitech May 13 23:45:21 volumio kernel: logitech-djreceiver 0003:046D:C52B.00BF: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 13 23:45:21 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.00BF/0003:046D:404D.00C0/input/input62 May 13 23:45:21 volumio kernel: logitech-hidpp-device 0003:046D:404D.00C0: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 13 23:45:22 volumio systemd-udevd[8794]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:45:22 volumio systemd-udevd[8780]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:45:22 volumio systemd-logind[822]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) May 13 23:45:22 volumio volumio[1083]: 0+0 records in May 13 23:45:22 volumio volumio[1083]: 0+0 records out May 13 23:45:22 volumio volumio[1083]: 0 bytes copied, 5.0925e-05 s, 0.0 kB/s May 13 23:45:23 volumio volumio[1083]: 0+0 records in May 13 23:45:23 volumio volumio[1083]: 0+0 records out May 13 23:45:23 volumio volumio[1083]: 0 bytes copied, 4.724e-05 s, 0.0 kB/s May 13 23:45:24 volumio volumio[1083]: 0+0 records in May 13 23:45:24 volumio volumio[1083]: 0+0 records out May 13 23:45:24 volumio volumio[1083]: 0 bytes copied, 4.7444e-05 s, 0.0 kB/s May 13 23:45:25 volumio volumio[1083]: 0+0 records in May 13 23:45:25 volumio volumio[1083]: 0+0 records out May 13 23:45:25 volumio volumio[1083]: 0 bytes copied, 4.8259e-05 s, 0.0 kB/s May 13 23:45:26 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:45:26 volumio volumio[1083]: 0+0 records in May 13 23:45:26 volumio volumio[1083]: 0+0 records out May 13 23:45:26 volumio volumio[1083]: 0 bytes copied, 4.9241e-05 s, 0.0 kB/s May 13 23:45:27 volumio volumio[1083]: 0+0 records in May 13 23:45:27 volumio volumio[1083]: 0+0 records out May 13 23:45:27 volumio volumio[1083]: 0 bytes copied, 4.8592e-05 s, 0.0 kB/s May 13 23:45:28 volumio volumio[1083]: 0+0 records in May 13 23:45:28 volumio volumio[1083]: 0+0 records out May 13 23:45:28 volumio volumio[1083]: 0 bytes copied, 3.8e-05 s, 0.0 kB/s May 13 23:45:28 volumio volumio[1083]: info: Preload queue cleared May 13 23:45:29 volumio volumio[1083]: 0+0 records in May 13 23:45:29 volumio volumio[1083]: 0+0 records out May 13 23:45:29 volumio volumio[1083]: 0 bytes copied, 4.7593e-05 s, 0.0 kB/s May 13 23:45:30 volumio volumio[1083]: 0+0 records in May 13 23:45:30 volumio volumio[1083]: 0+0 records out May 13 23:45:30 volumio volumio[1083]: 0 bytes copied, 4.7611e-05 s, 0.0 kB/s May 13 23:45:31 volumio volumio[1083]: 0+0 records in May 13 23:45:31 volumio volumio[1083]: 0+0 records out May 13 23:45:31 volumio volumio[1083]: 0 bytes copied, 4.75e-05 s, 0.0 kB/s May 13 23:45:32 volumio volumio[1083]: 0+0 records in May 13 23:45:32 volumio volumio[1083]: 0+0 records out May 13 23:45:32 volumio volumio[1083]: 0 bytes copied, 4.8315e-05 s, 0.0 kB/s May 13 23:45:33 volumio volumio[1083]: 0+0 records in May 13 23:45:33 volumio volumio[1083]: 0+0 records out May 13 23:45:33 volumio volumio[1083]: 0 bytes copied, 4.7593e-05 s, 0.0 kB/s May 13 23:45:34 volumio volumio[1083]: 0+0 records in May 13 23:45:34 volumio volumio[1083]: 0+0 records out May 13 23:45:34 volumio volumio[1083]: 0 bytes copied, 4.8926e-05 s, 0.0 kB/s May 13 23:45:35 volumio volumio[1083]: 0+0 records in May 13 23:45:35 volumio volumio[1083]: 0+0 records out May 13 23:45:35 volumio volumio[1083]: 0 bytes copied, 4.7185e-05 s, 0.0 kB/s May 13 23:45:36 volumio volumio[1083]: 0+0 records in May 13 23:45:36 volumio volumio[1083]: 0+0 records out May 13 23:45:36 volumio volumio[1083]: 0 bytes copied, 4.8314e-05 s, 0.0 kB/s May 13 23:45:37 volumio volumio[1083]: 0+0 records in May 13 23:45:37 volumio volumio[1083]: 0+0 records out May 13 23:45:37 volumio volumio[1083]: 0 bytes copied, 4.8518e-05 s, 0.0 kB/s May 13 23:45:38 volumio volumio[1083]: 0+0 records in May 13 23:45:38 volumio volumio[1083]: 0+0 records out May 13 23:45:38 volumio volumio[1083]: 0 bytes copied, 4.8093e-05 s, 0.0 kB/s May 13 23:45:39 volumio volumio[1083]: 0+0 records in May 13 23:45:39 volumio volumio[1083]: 0+0 records out May 13 23:45:39 volumio volumio[1083]: 0 bytes copied, 6.8093e-05 s, 0.0 kB/s May 13 23:45:40 volumio volumio[1083]: 0+0 records in May 13 23:45:40 volumio volumio[1083]: 0+0 records out May 13 23:45:40 volumio volumio[1083]: 0 bytes copied, 4.763e-05 s, 0.0 kB/s May 13 23:45:41 volumio volumio[1083]: 0+0 records in May 13 23:45:41 volumio volumio[1083]: 0+0 records out May 13 23:45:41 volumio volumio[1083]: 0 bytes copied, 4.9463e-05 s, 0.0 kB/s May 13 23:45:42 volumio volumio[1083]: 0+0 records in May 13 23:45:42 volumio volumio[1083]: 0+0 records out May 13 23:45:42 volumio volumio[1083]: 0 bytes copied, 5.0019e-05 s, 0.0 kB/s May 13 23:45:44 volumio volumio[1083]: 0+0 records in May 13 23:45:44 volumio volumio[1083]: 0+0 records out May 13 23:45:44 volumio volumio[1083]: 0 bytes copied, 5.3444e-05 s, 0.0 kB/s May 13 23:45:45 volumio volumio[1083]: 0+0 records in May 13 23:45:45 volumio volumio[1083]: 0+0 records out May 13 23:45:45 volumio volumio[1083]: 0 bytes copied, 4.8759e-05 s, 0.0 kB/s May 13 23:45:46 volumio volumio[1083]: 0+0 records in May 13 23:45:46 volumio volumio[1083]: 0+0 records out May 13 23:45:46 volumio volumio[1083]: 0 bytes copied, 4.8167e-05 s, 0.0 kB/s May 13 23:45:46 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:45:46 volumio volumio[1083]: info: Preload queue cleared May 13 23:45:47 volumio volumio[1083]: 0+0 records in May 13 23:45:47 volumio volumio[1083]: 0+0 records out May 13 23:45:47 volumio volumio[1083]: 0 bytes copied, 5.0797e-05 s, 0.0 kB/s May 13 23:45:47 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:45:47 volumio volumio[1083]: info: Preload queue cleared May 13 23:45:48 volumio volumio[1083]: 0+0 records in May 13 23:45:48 volumio volumio[1083]: 0+0 records out May 13 23:45:48 volumio volumio[1083]: 0 bytes copied, 5.326e-05 s, 0.0 kB/s May 13 23:45:48 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 23:45:48 volumio volumio[1083]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 23:45:48 volumio volumio[1083]: TypeError: Cannot read property 'length' of undefined May 13 23:45:48 volumio volumio[1083]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 13 23:45:48 volumio volumio[1083]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 13 23:45:48 volumio volumio[1083]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 13 23:45:48 volumio volumio[1083]: at Parser.emit (events.js:315:20) May 13 23:45:48 volumio volumio[1083]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 13 23:45:48 volumio volumio[1083]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 13 23:45:48 volumio volumio[1083]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 13 23:45:48 volumio volumio[1083]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 13 23:45:48 volumio volumio[1083]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 13 23:45:48 volumio volumio[1083]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 13 23:45:48 volumio volumio[1083]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 13 23:45:48 volumio volumio[1083]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 13 23:45:48 volumio volumio[1083]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 13 23:45:48 volumio volumio[1083]: at IncomingMessage.emit (events.js:327:22) May 13 23:45:48 volumio volumio[1083]: at endReadableNT (internal/streams/readable.js:1327:12) May 13 23:45:48 volumio volumio[1083]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 13 23:45:48 volumio volumio[1083]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 23:45:49 volumio sudo[9134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 23:44 May 13 23:45:49 volumio sudo[9134]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"