Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:00 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 06 23:40:00 volumio volumio[1320]: info: CURURI: artists://Vicci%20Martinez/Vicci Dec 06 23:40:00 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:00 volumio volumio[1320]: 2+0 records in Dec 06 23:40:00 volumio volumio[1320]: 2+0 records out Dec 06 23:40:00 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000371039 s, 177 MB/s Dec 06 23:40:00 volumio volumio[1320]: info: Preload queue cleared Dec 06 23:40:00 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:00 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:00 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:00 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:00 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:00 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:00 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:00 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:01 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:01 volumio volumio[1320]: 2+0 records in Dec 06 23:40:01 volumio volumio[1320]: 2+0 records out Dec 06 23:40:01 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000566716 s, 116 MB/s Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:02 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:02 volumio volumio[1320]: 2+0 records in Dec 06 23:40:02 volumio volumio[1320]: 2+0 records out Dec 06 23:40:02 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000399737 s, 164 MB/s Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:03 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:03 volumio volumio[1320]: 2+0 records in Dec 06 23:40:03 volumio volumio[1320]: 2+0 records out Dec 06 23:40:03 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000604789 s, 108 MB/s Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:04 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:04 volumio volumio[1320]: 2+0 records in Dec 06 23:40:04 volumio volumio[1320]: 2+0 records out Dec 06 23:40:04 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000653902 s, 100 MB/s Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:05 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 06 23:40:05 volumio volumio[1320]: info: CURURI: artists://Vicci%20Martinez/Vicci%20%5BHD%5D%20%20(M4a) Dec 06 23:40:05 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:05 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:05 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:05 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:05 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:05 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:05 volumio volumio[1320]: info: Executing endpoint metavolumio Dec 06 23:40:05 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 06 23:40:05 volumio volumio[1320]: info: Preload queue cleared Dec 06 23:40:05 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:05 volumio volumio[1320]: 2+0 records in Dec 06 23:40:05 volumio volumio[1320]: 2+0 records out Dec 06 23:40:05 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000985151 s, 66.5 MB/s Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:06 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:07 volumio volumio[1320]: 2+0 records in Dec 06 23:40:07 volumio volumio[1320]: 2+0 records out Dec 06 23:40:07 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00100218 s, 65.4 MB/s Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:07 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:08 volumio volumio[1320]: 2+0 records in Dec 06 23:40:08 volumio volumio[1320]: 2+0 records out Dec 06 23:40:08 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000568538 s, 115 MB/s Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:08 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: info: Preload queue cleared Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::ClearQueue Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::stop Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::stPlaybackTimer Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 8 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 8 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::serviceStop Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 8 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::serviceStop Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::stop Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::clearPlayQueue Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::saveQueue Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushQueue Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::addQueueItems Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::addQueueItems Dec 06 23:40:09 volumio volumio[1320]: info: Preload queue cleared Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Come Along.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Come Along.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Out of Control.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Out of Control.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Can Love_2.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Can Love_2.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Can Love.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Can Love.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Hold Me Darlin'_1.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Hold Me Darlin'_1.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Hold Me Darlin'.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Hold Me Darlin'.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Not Washing You Off of Me.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Not Washing You Off of Me.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushQueue Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::saveQueue Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 8 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPlay Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::play index 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::addQueueItems Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::addQueueItems Dec 06 23:40:09 volumio volumio[1320]: info: Preload queue cleared Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Let Go.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Let Go.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Touch That Fire.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Touch That Fire.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Little Faith.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Little Faith.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Snake Charmer.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Snake Charmer.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Adding Item to queue: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Alive.m4a Dec 06 23:40:09 volumio volumio[1320]: info: Using cached record of: music-library/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/Alive.m4a Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::stop Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushQueue Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::saveQueue Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::play index undefined Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::startPlaybackTimer Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::clearAddPlayTracks NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 23:40:09 volumio volumio[1320]: 0+0 records in Dec 06 23:40:09 volumio volumio[1320]: 0+0 records out Dec 06 23:40:09 volumio volumio[1320]: 0 bytes copied, 0.000158229 s, 0.0 kB/s Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand stop took 94 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand stop took 58 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Dec 06 23:40:09 volumio volumio[1320]: info: Ignoring MPD Status Update Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 12 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand clear took 11 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 10 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand add "NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a" Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 06 23:40:09 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState stateService stop Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus stop Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: No code Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 39ms Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Dec 06 23:40:09 volumio volumio[1320]: info: Ignoring MPD Status Update Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: updateQueue error: null Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Dec 06 23:40:09 volumio volumio[1320]: info: Ignoring MPD Status Update Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 48ms Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand add "NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a" took 39 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 14ms Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand play Dec 06 23:40:09 volumio volumio[1320]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 61ms Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Dec 06 23:40:09 volumio volumio[1320]: info: Ignoring MPD Status Update Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 43ms Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand play took 38 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 36ms Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 8 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Nulleq applied Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: Dec 06 23:40:09 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 23 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:09 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus stop Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 43ms Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 15 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand status took 13 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:09 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Dec 06 23:40:09 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 74ms Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 06 23:40:09 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:09 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:09 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Dec 06 23:40:09 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:09 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Dec 06 23:40:09 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:09 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:09 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:09 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 117ms Dec 06 23:40:09 volumio volumio[1320]: info: ------------------------------ 115ms Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: info: FusionDsp - Volumio is playing Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:09 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:10 volumio volumio[1320]: 2+0 records in Dec 06 23:40:10 volumio volumio[1320]: 2+0 records out Dec 06 23:40:10 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000663955 s, 98.7 MB/s Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:10 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:11 volumio volumio[1320]: 2+0 records in Dec 06 23:40:11 volumio volumio[1320]: 2+0 records out Dec 06 23:40:11 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000945828 s, 69.3 MB/s Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:11 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:12 volumio volumio[1320]: 2+0 records in Dec 06 23:40:12 volumio volumio[1320]: 2+0 records out Dec 06 23:40:12 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000651923 s, 101 MB/s Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:12 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:13 volumio volumio[1320]: 2+0 records in Dec 06 23:40:13 volumio volumio[1320]: 2+0 records out Dec 06 23:40:13 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000639371 s, 103 MB/s Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:13 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:14 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:14 volumio volumio[1320]: 2+0 records in Dec 06 23:40:14 volumio volumio[1320]: 2+0 records out Dec 06 23:40:14 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000381821 s, 172 MB/s Dec 06 23:40:15 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:15 volumio volumio[1320]: 2+0 records in Dec 06 23:40:15 volumio volumio[1320]: 2+0 records out Dec 06 23:40:15 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000566612 s, 116 MB/s Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:15 volumio kernel: hwmon hwmon1: Voltage normalised Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:15 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:16 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:16 volumio volumio[1320]: 2+0 records in Dec 06 23:40:16 volumio volumio[1320]: 2+0 records out Dec 06 23:40:16 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000406248 s, 161 MB/s Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:17 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:17 volumio volumio[1320]: 2+0 records in Dec 06 23:40:17 volumio volumio[1320]: 2+0 records out Dec 06 23:40:17 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000376612 s, 174 MB/s Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:17 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:18 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:18 volumio volumio[1320]: 2+0 records in Dec 06 23:40:18 volumio volumio[1320]: 2+0 records out Dec 06 23:40:18 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000389685 s, 168 MB/s Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:19 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:19 volumio volumio[1320]: 2+0 records in Dec 06 23:40:19 volumio volumio[1320]: 2+0 records out Dec 06 23:40:19 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000372237 s, 176 MB/s Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:20 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:20 volumio volumio[1320]: 2+0 records in Dec 06 23:40:20 volumio volumio[1320]: 2+0 records out Dec 06 23:40:20 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00041031 s, 160 MB/s Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:21 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:21 volumio volumio[1320]: 2+0 records in Dec 06 23:40:21 volumio volumio[1320]: 2+0 records out Dec 06 23:40:21 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000373956 s, 175 MB/s Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:22 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:22 volumio volumio[1320]: 2+0 records in Dec 06 23:40:22 volumio volumio[1320]: 2+0 records out Dec 06 23:40:22 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00040729 s, 161 MB/s Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:23 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:23 volumio volumio[1320]: 2+0 records in Dec 06 23:40:23 volumio volumio[1320]: 2+0 records out Dec 06 23:40:23 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000597653 s, 110 MB/s Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:24 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:24 volumio volumio[1320]: 2+0 records in Dec 06 23:40:24 volumio volumio[1320]: 2+0 records out Dec 06 23:40:24 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000376769 s, 174 MB/s Dec 06 23:40:25 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:25 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:25 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 23:40:25 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 23:40:25 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 06 23:40:26 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 06 23:40:26 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:26 volumio volumio[1320]: 2+0 records in Dec 06 23:40:26 volumio volumio[1320]: 2+0 records out Dec 06 23:40:26 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00307576 s, 21.3 MB/s Dec 06 23:40:26 volumio volumio[1320]: info: Received Get System Version Dec 06 23:40:26 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 23:40:26 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:26 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:27 volumio volumio[1320]: 2+0 records in Dec 06 23:40:27 volumio volumio[1320]: 2+0 records out Dec 06 23:40:27 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00093713 s, 69.9 MB/s Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:27 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:28 volumio volumio[1320]: 2+0 records in Dec 06 23:40:28 volumio volumio[1320]: 2+0 records out Dec 06 23:40:28 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000664215 s, 98.7 MB/s Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:28 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:29 volumio volumio[1320]: 2+0 records in Dec 06 23:40:29 volumio volumio[1320]: 2+0 records out Dec 06 23:40:29 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000596559 s, 110 MB/s Dec 06 23:40:29 volumio volumio[1320]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:29 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:30 volumio volumio[1320]: 2+0 records in Dec 06 23:40:30 volumio volumio[1320]: 2+0 records out Dec 06 23:40:30 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000383748 s, 171 MB/s Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:30 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:31 volumio volumio[1320]: 2+0 records in Dec 06 23:40:31 volumio volumio[1320]: 2+0 records out Dec 06 23:40:31 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000571038 s, 115 MB/s Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:31 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::Reload Ui Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 06 23:40:32 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:32 volumio volumio[1320]: 2+0 records in Dec 06 23:40:32 volumio volumio[1320]: 2+0 records out Dec 06 23:40:32 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000689006 s, 95.1 MB/s Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio sudo[14153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 23:40:32 volumio sudo[14153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:32 volumio sudo[14151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio sudo[14151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio sudo[14151]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio sudo[14153]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:32 volumio volumio[1320]: verbose: New Socket.io Connection to 192.168.7.247 from 192.168.7.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 06 23:40:32 volumio volumio[1320]: info: Received Get System Info Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 23:40:32 volumio volumio[1320]: info: Discovery: Getting this device information Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:32 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:32 volumio volumio[1320]: info: Retrieving Cloud Streaming UI Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 23:40:32 volumio volumio[1320]: info: Getting Tidal Cloud Configuration Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:32 volumio volumio[1320]: info: Getting Qobuz Cloud Configuration Dec 06 23:40:32 volumio volumio[1320]: info: Asking plugin for UI Config Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:32 volumio volumio[1320]: info: Getting Spotify Cloud Configuration Dec 06 23:40:32 volumio volumio[1320]: info: Asking plugin for UI Config Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:32 volumio volumio[1320]: info: Saving Spotify Acccount Dec 06 23:40:32 volumio volumio[1320]: info: Got it Dec 06 23:40:32 volumio volumio[1320]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 06 23:40:32 volumio volumio-remote-updater[775]: Test mode disabled Dec 06 23:40:32 volumio volumio-remote-updater[775]: Alpha mode disabled Dec 06 23:40:32 volumio volumio-remote-updater[775]: Alpha legacy test mode disabled Dec 06 23:40:32 volumio volumio[1320]: info: Got Tidal Cloud Configuration Dec 06 23:40:32 volumio volumio[1320]: info: Got it Dec 06 23:40:32 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:33 volumio volumio[1320]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 06 23:40:33 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 06 23:40:33 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:33 volumio volumio[1320]: 2+0 records in Dec 06 23:40:33 volumio volumio[1320]: 2+0 records out Dec 06 23:40:33 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000709631 s, 92.4 MB/s Dec 06 23:40:33 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 06 23:40:33 volumio volumio[1320]: info: Loading i18n strings for locale en Dec 06 23:40:34 volumio volumio[1320]: info: [now-playing] Volumio language changed - pushing localization settings Dec 06 23:40:34 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 06 23:40:34 volumio volumio[1320]: info: Setting timezone to America/Chicago Dec 06 23:40:34 volumio sudo[14175]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 06 23:40:34 volumio sudo[14175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:34 volumio sudo[14175]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:34 volumio sudo[14179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Dec 06 23:40:34 volumio sudo[14179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:34 volumio sudo[14179]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:34 volumio sudo[14184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 06 23:40:34 volumio sudo[14184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:34 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 06 23:40:34 volumio sudo[14184]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:34 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 06 23:40:34 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... Dec 06 23:40:34 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 06 23:40:34 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 06 23:40:34 volumio sudo[14193]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Dec 06 23:40:34 volumio sudo[14193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:34 volumio dbus-daemon[751]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.87' (uid=0 pid=14195 comm="/usr/bin/timedatectl set-timezone America/Chicago") Dec 06 23:40:35 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 06 23:40:35 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 06 23:40:35 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 06 23:40:36 volumio dbus-daemon[751]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 06 23:40:36 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 06 23:40:36 volumio sudo[14193]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:36 volumio volumio[1320]: info: Fetching Streaming Services browse cache Dec 06 23:40:36 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:36 volumio volumio[1320]: 2+0 records in Dec 06 23:40:36 volumio volumio[1320]: 2+0 records out Dec 06 23:40:36 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00130109 s, 50.4 MB/s Dec 06 23:40:36 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 23:40:36 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:36 volumio volumio[1320]: Updating browse sources language Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source TIDAL Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source 80s80s Radio Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source Jellyfin Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source Randomizer Dec 06 23:40:36 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source TIDAL Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source 80s80s Radio Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source Jellyfin Dec 06 23:40:36 volumio volumio[1320]: Cannot find translation for source Randomizer Dec 06 23:40:37 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:37 volumio volumio[1320]: 2+0 records in Dec 06 23:40:37 volumio volumio[1320]: 2+0 records out Dec 06 23:40:37 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000665986 s, 98.4 MB/s Dec 06 23:40:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 06 23:40:38 volumio volumio[1320]: info: Loading i18n strings for locale en Dec 06 23:40:38 volumio volumio[1320]: info: [now-playing] Volumio language changed - pushing localization settings Dec 06 23:40:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 06 23:40:38 volumio volumio[1320]: info: Setting timezone to America/Chicago Dec 06 23:40:38 volumio sudo[14304]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 06 23:40:38 volumio sudo[14304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:38 volumio sudo[14304]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:38 volumio sudo[14308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Dec 06 23:40:38 volumio sudo[14308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:38 volumio sudo[14308]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:38 volumio sudo[14313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 06 23:40:38 volumio sudo[14313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:38 volumio sudo[14313]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:38 volumio sudo[14317]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Dec 06 23:40:38 volumio sudo[14317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:38 volumio sudo[14317]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 06 23:40:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 06 23:40:38 volumio sudo[14320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 06 23:40:38 volumio sudo[14320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:38 volumio volumio[1320]: Updating browse sources language Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source TIDAL Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source 80s80s Radio Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source Jellyfin Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source Randomizer Dec 06 23:40:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source TIDAL Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source 80s80s Radio Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source Jellyfin Dec 06 23:40:38 volumio volumio[1320]: Cannot find translation for source Randomizer Dec 06 23:40:38 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:38 volumio volumio[1320]: 2+0 records in Dec 06 23:40:38 volumio volumio[1320]: 2+0 records out Dec 06 23:40:38 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000672288 s, 97.5 MB/s Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: info: Fetching Streaming Services browse cache Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:38 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:39 volumio sudo[14320]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:39 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:39 volumio volumio[1320]: 2+0 records in Dec 06 23:40:39 volumio volumio[1320]: 2+0 records out Dec 06 23:40:39 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00070161 s, 93.4 MB/s Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:40 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:40 volumio volumio[1320]: 2+0 records in Dec 06 23:40:40 volumio volumio[1320]: 2+0 records out Dec 06 23:40:40 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.00105385 s, 62.2 MB/s Dec 06 23:40:41 volumio systemd[1]: apt-daily.service: Deactivated successfully. Dec 06 23:40:41 volumio systemd[1]: Finished apt-daily.service - Daily apt download activities. Dec 06 23:40:41 volumio systemd[1]: apt-daily.service: Consumed 4.138s CPU time. Dec 06 23:40:41 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:41 volumio volumio[1320]: 2+0 records in Dec 06 23:40:41 volumio volumio[1320]: 2+0 records out Dec 06 23:40:41 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000693746 s, 94.5 MB/s Dec 06 23:40:42 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 06 23:40:42 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 06 23:40:42 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 23:40:42 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:42 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:42 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:43 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:43 volumio volumio[1320]: 2+0 records in Dec 06 23:40:43 volumio volumio[1320]: 2+0 records out Dec 06 23:40:43 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000551142 s, 119 MB/s Dec 06 23:40:44 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:44 volumio volumio[1320]: 2+0 records in Dec 06 23:40:44 volumio volumio[1320]: 2+0 records out Dec 06 23:40:44 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000399425 s, 164 MB/s Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio kernel: hwmon hwmon1: Voltage normalised Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:44 volumio volumio[1320]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 23:40:45 volumio volumio[1320]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Dec 06 23:40:45 volumio volumio[1320]: 2+0 records in Dec 06 23:40:45 volumio volumio[1320]: 2+0 records out Dec 06 23:40:45 volumio volumio[1320]: 65536 bytes (66 kB, 64 KiB) copied, 0.000374321 s, 175 MB/s Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 06 23:40:45 volumio volumio[1320]: info: Preparing to save Alsa Options, stopping services first Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:45 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::volumioPause Dec 06 23:40:45 volumio volumio[1320]: info: CoreStateMachine::pause Dec 06 23:40:45 volumio volumio[1320]: info: CoreStateMachine::stPlaybackTimer Dec 06 23:40:45 volumio volumio[1320]: info: CoreStateMachine::servicePause Dec 06 23:40:45 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::servicePause Dec 06 23:40:45 volumio volumio[1320]: info: ControllerMpd::pause Dec 06 23:40:45 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand pause Dec 06 23:40:45 volumio volumio[1320]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:45 volumio volumio[1320]: info: Found match in Cards Database: setting mixer PCM for card Headphones Dec 06 23:40:45 volumio volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:45 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:45 volumio vtcs[20773]: [2025-12-06 23:40:45.708] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 06 23:40:45 volumio vtcs[20773]: [2025-12-06 23:40:45.708] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 06 23:40:45 volumio sudo[14406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:45 volumio sudo[14406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 06 23:40:45 volumio volumio[1320]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 06 23:40:45 volumio volumio[1320]: info: Disabling external Volume Control Dec 06 23:40:45 volumio volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:45 volumio volumio[1320]: info: Preparing to generate the ALSA configuration file Dec 06 23:40:45 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 06 23:40:45 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 06 23:40:45 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 06 23:40:45 volumio systemd[1]: vtcs.service: Consumed 25.116s CPU time. Dec 06 23:40:45 volumio sudo[14406]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:46 volumio sudo[14414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:46 volumio sudo[14414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio volumio[1320]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 06 23:40:46 volumio volumio[1320]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 23:40:46 volumio volumio[1320]: info: Reading ALSA contributions from plugins. Dec 06 23:40:46 volumio volumio[1320]: info: Dec 06 23:40:46 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:46 volumio volumio[1320]: info: sendMpdCommand pause took 414 milliseconds Dec 06 23:40:46 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:46 volumio volumio[1320]: info: Dec 06 23:40:46 volumio volumio[1320]: ---------------------------- MPD announces state update: player Dec 06 23:40:46 volumio volumio[1320]: info: ControllerMpd::getState Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Dec 06 23:40:46 volumio volumio[1320]: info: sendMpdCommand status took 33 milliseconds Dec 06 23:40:46 volumio volumio[1320]: info: sendMpdCommand status took 31 milliseconds Dec 06 23:40:46 volumio sudo[14421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 23:40:46 volumio sudo[14421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::parseState Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 23:40:46 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 06 23:40:46 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:46 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Dec 06 23:40:46 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2469,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5123 Kbps","isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:46 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::syncState stateService pause Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus pause Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:46 volumio sudo[14414]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::stPlaybackTimer Dec 06 23:40:46 volumio volumio[1320]: info: ControllerMpd::pushState Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2469,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5123 Kbps","isStreaming":false,"title":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","uri":"NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","trackType":"m4a"} Dec 06 23:40:46 volumio volumio[1320]: verbose: CURRENT POSITION 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::syncState stateService pause Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus pause Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::stPlaybackTimer Dec 06 23:40:46 volumio sudo[14421]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:46 volumio volumio[1320]: info: ------------------------------ 133ms Dec 06 23:40:46 volumio volumio[1320]: info: ------------------------------ 131ms Dec 06 23:40:46 volumio volumio[1320]: 0+0 records in Dec 06 23:40:46 volumio volumio[1320]: 0+0 records out Dec 06 23:40:46 volumio volumio[1320]: 0 bytes copied, 0.000344321 s, 0.0 kB/s Dec 06 23:40:46 volumio sudo[14427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 23:40:46 volumio sudo[14427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 23:40:46 volumio qobuz-connect[20741]: 20251206 23:40:46.493 [20741.20741] INFO SampleApp: Stopping Local configuration server Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:46 volumio volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:40:46 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:46 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:46 volumio volumio[1320]: info: Asound.conf file unchanged, so no further update is needed Dec 06 23:40:46 volumio volumio[1320]: info: Output device has changed, restarting MPD Dec 06 23:40:46 volumio volumio[1320]: info: Output device has changed, restarting Shairport Sync Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:46 volumio sudo[14456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 23:40:46 volumio sudo[14456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio sudo[14458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 23:40:46 volumio sudo[14458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio sudo[14456]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:46 volumio volumio[1320]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 23:40:46 volumio volumio[1320]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:46 volumio volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:46 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:46 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:46 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 23:40:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 06 23:40:46 volumio sudo[14468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:46 volumio sudo[14468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio sudo[14470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:46 volumio sudo[14470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:46 volumio volumio[1320]: info: MPD Permissions set Dec 06 23:40:46 volumio sudo[14477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 23:40:46 volumio sudo[14477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio sudo[14477]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:47 volumio sudo[14486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 23:40:47 volumio sudo[14486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:47 volumio sudo[14468]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:47 volumio sudo[14470]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:47 volumio volumio[1320]: info: Starting Shairport Sync Dec 06 23:40:47 volumio sudo[14491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 23:40:47 volumio sudo[14491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 23:40:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 23:40:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:40:47 volumio systemd[1]: shairport-sync.service: Consumed 3min 8.237s CPU time. Dec 06 23:40:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:40:47 volumio sudo[14491]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:47 volumio volumio[1320]: 0+0 records in Dec 06 23:40:47 volumio volumio[1320]: 0+0 records out Dec 06 23:40:47 volumio volumio[1320]: 0 bytes copied, 0.000562184 s, 0.0 kB/s Dec 06 23:40:47 volumio volumio[1320]: info: Shairport-Sync Started Dec 06 23:40:47 volumio qobuz-connect[20741]: 20251206 23:40:47.830 [20741.20741] INFO SampleApp: shat down connection on UNIX socket Dec 06 23:40:47 volumio volumio[1320]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 06 23:40:47 volumio volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:47 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:47 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:47 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 06 23:40:47 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:40:47 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:40:47 volumio sudo[14486]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:47 volumio sudo[14427]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:48 volumio volumio[1320]: info: Executing endpoint qc_getconfig Dec 06 23:40:48 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.345 [14500.14500] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 06 23:40:48 volumio volumio[1320]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 06 23:40:48 volumio volumio[1320]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.365 [14500.14500] INFO VolumeManager: [0x16925a8]: Setting new playback volume: 75 Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.365 [14500.14500] INFO VolumeManager: [0x16925a8]: Setting new mute state: 0 Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.365 [14500.14500] INFO QobuzConnect: [0x1692f78]: Client initialized! Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.365 [14500.14500] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.410 [14500.14500] INFO LocalConfigManager: [0x1691e88]: Starting Local Configuration server Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.410 [14500.14500] INFO SampleApp: Starting Local configuration server Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.411 [14500.14500] INFO SampleApp: Connected to UNIX socket client 0x167c818 Dec 06 23:40:48 volumio qobuz-connect[14500]: 20251206 23:40:48.495 [14500.14500] INFO SampleApp: Playback volume changed: 75 Dec 06 23:40:48 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:48 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:48 volumio volumio[1320]: 0+0 records in Dec 06 23:40:48 volumio volumio[1320]: 0+0 records out Dec 06 23:40:48 volumio volumio[1320]: 0 bytes copied, 0.000539893 s, 0.0 kB/s Dec 06 23:40:48 volumio volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:49 volumio volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:49 volumio volumio[1320]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:40:49 volumio volumio[1320]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:40:49 volumio sudo[14536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 23:40:49 volumio sudo[14536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:49 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 06 23:40:49 volumio sudo[14536]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:49 volumio volumio[1320]: 0+0 records in Dec 06 23:40:49 volumio volumio[1320]: 0+0 records out Dec 06 23:40:49 volumio volumio[1320]: 0 bytes copied, 0.0003388 s, 0.0 kB/s Dec 06 23:40:49 volumio volumio[1320]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 06 23:40:49 volumio volumio[1320]: info: CoreStateMachine::getcurrentVolume Dec 06 23:40:49 volumio volumio[1320]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 23:40:49 volumio volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:40:49 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:49 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:49 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:49 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:49 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:49 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:49 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:49 volumio volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:40:49 volumio volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:40:49 volumio volumio[1320]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 23:40:50 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:50 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:50 volumio volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:40:50 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:50 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:50 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:50 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:50 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:50 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:50 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:50 volumio volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:50 volumio volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:50 volumio volumio[1320]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:40:50 volumio volumio[1320]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:40:50 volumio sudo[14560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 23:40:50 volumio sudo[14560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:50 volumio sudo[14560]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:50 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 23:40:50 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 23:40:50 volumio systemd[1]: mpd.service: Consumed 21min 57.047s CPU time. Dec 06 23:40:50 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 23:40:50 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 23:40:50 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 23:40:50 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 23:40:50 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 23:40:50 volumio volumio[1320]: 0+0 records in Dec 06 23:40:50 volumio volumio[1320]: 0+0 records out Dec 06 23:40:50 volumio volumio[1320]: 0 bytes copied, 0.000623799 s, 0.0 kB/s Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 23:40:50 volumio volumio[1320]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 06 23:40:50 volumio volumio[1320]: info: Received Get System Version Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 23:40:50 volumio volumio[1320]: info: Received Get System Info Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 23:40:50 volumio volumio[1320]: info: Discovery: Getting this device information Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:50 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:50 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 23:40:51 volumio sudo[14564]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 23:40:51 volumio sudo[14564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 23:40:51 volumio sudo[14564]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:51 volumio volumio[1320]: info: Executing endpoint tc_getconfig Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 06 23:40:51 volumio vtcs[14539]: STARTING TidalConnect services, version: 1.5.2.56 Dec 06 23:40:51 volumio vtcs[14539]: STARTED TidalConnect services. Dec 06 23:40:51 volumio volumio[1320]: info: Executing endpoint tc_connect Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 06 23:40:51 volumio volumio[1320]: info: Connecting to TidalConnect Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:51 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:51 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:51 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:40:51 volumio volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:51 volumio volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:51 volumio volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:51 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:51 volumio volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 06 23:40:51 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:51 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:51 volumio volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:51 volumio volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:51 volumio volumio[1320]: 0+0 records in Dec 06 23:40:51 volumio volumio[1320]: 0+0 records out Dec 06 23:40:51 volumio volumio[1320]: 0 bytes copied, 0.000304686 s, 0.0 kB/s Dec 06 23:40:52 volumio volumio[1320]: info: TidalConnect service started! Dec 06 23:40:53 volumio volumio[1320]: 0+0 records in Dec 06 23:40:53 volumio volumio[1320]: 0+0 records out Dec 06 23:40:53 volumio volumio[1320]: 0 bytes copied, 0.000368175 s, 0.0 kB/s Dec 06 23:40:53 volumio volumio[1320]: info: TidalConnect service started! Dec 06 23:40:54 volumio volumio[1320]: 0+0 records in Dec 06 23:40:54 volumio volumio[1320]: 0+0 records out Dec 06 23:40:54 volumio volumio[1320]: 0 bytes copied, 0.000553799 s, 0.0 kB/s Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 06 23:40:54 volumio volumio[1320]: info: System name has changed, restarting Shairport Sync Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 23:40:54 volumio volumio[1320]: info: Updating MyVolumio device info Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:54 volumio vtcs[14539]: [2025-12-06 23:40:54.463] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 06 23:40:54 volumio vtcs[14539]: [2025-12-06 23:40:54.464] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 06 23:40:54 volumio volumio[1320]: info: Discovery: Restarting Advertising due to device name change Dec 06 23:40:54 volumio sudo[14616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:54 volumio sudo[14616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:54 volumio sudo[14618]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 06 23:40:54 volumio sudo[14618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:54 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 06 23:40:54 volumio systemd[1]: vtcs.service: Killing process 14580 (vtcs) with signal SIGKILL. Dec 06 23:40:54 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 06 23:40:54 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 06 23:40:54 volumio systemd[1]: vtcs.service: Consumed 1.093s CPU time. Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 06 23:40:54 volumio sudo[14616]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:54 volumio volumio[1320]: info: Starting Shairport Sync Dec 06 23:40:55 volumio volumio[1320]: error: Failed to update MyVolumio device: {"message":"Unauthenticated"} Dec 06 23:40:55 volumio volumio[1320]: 0+0 records in Dec 06 23:40:55 volumio volumio[1320]: 0+0 records out Dec 06 23:40:55 volumio volumio[1320]: 0 bytes copied, 0.000280832 s, 0.0 kB/s Dec 06 23:40:55 volumio sudo[14637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 23:40:55 volumio sudo[14628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 23:40:55 volumio sudo[14628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio sudo[14637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio sudo[14635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 06 23:40:55 volumio sudo[14635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio sudo[14618]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio sudo[14621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:55 volumio volumio[1320]: info: Discovery: A device disappeared from network Dec 06 23:40:55 volumio volumio[1320]: info: Discovery: Device volumio disappeared from network Dec 06 23:40:55 volumio sudo[14635]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio sudo[14621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio volumio[1320]: info: Discovery: A device disappeared from network Dec 06 23:40:55 volumio volumio[1320]: info: Permissions for /etc/hosts set Dec 06 23:40:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 23:40:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 23:40:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:40:55 volumio systemd[1]: shairport-sync.service: Consumed 2.112s CPU time. Dec 06 23:40:55 volumio sudo[14628]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio volumio[1320]: info: Hostname now is volumio-rpi3 Dec 06 23:40:55 volumio sudo[14650]: volumio : unable to resolve host volumio: System error Dec 06 23:40:55 volumio sudo[14649]: volumio : unable to resolve host volumio: System error Dec 06 23:40:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:40:55 volumio sudo[14653]: volumio : unable to resolve host volumio: System error Dec 06 23:40:55 volumio sudo[14637]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio sudo[14649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-rpi3 Dec 06 23:40:55 volumio sudo[14650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 23:40:55 volumio sudo[14649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio volumio[1320]: info: Shairport-Sync Started Dec 06 23:40:55 volumio sudo[14650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio-rpi3 sudo[14649]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio-rpi3 sudo[14653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 06 23:40:55 volumio-rpi3 sudo[14653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:55 volumio-rpi3 volumio[1320]: info: New hostname set Dec 06 23:40:55 volumio-rpi3 sudo[14621]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:55 volumio-rpi3 sudo[14653]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:56 volumio-rpi3 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: Permissions for /etc/avahi/services/volumio.service Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14500]: 20251206 23:40:56.047 [14500.14500] INFO SampleApp: Stopping Local configuration server Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: Avahi name changed to volumio-rpi3 Dec 06 23:40:56 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:40:56 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:40:56 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000285519 s, 0.0 kB/s Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14500]: 20251206 23:40:56.423 [14500.14500] INFO SampleApp: shat down connection on UNIX socket Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:56 volumio-rpi3 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 06 23:40:56 volumio-rpi3 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:40:56 volumio-rpi3 avahi-daemon[750]: Files changed, reloading. Dec 06 23:40:56 volumio-rpi3 avahi-daemon[750]: Loading service file /services/volumio.service. Dec 06 23:40:56 volumio-rpi3 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:40:56 volumio-rpi3 sudo[14650]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: Executing endpoint qc_getconfig Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.670 [14692.14692] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.693 [14692.14692] INFO VolumeManager: [0xca65a8]: Setting new playback volume: 75 Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.693 [14692.14692] INFO VolumeManager: [0xca65a8]: Setting new mute state: 0 Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.693 [14692.14692] INFO QobuzConnect: [0xca6f78]: Client initialized! Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.693 [14692.14692] INFO SampleApp: Starting Avahi advertising, name: Volumio RPi3, service name: _qobuz-connect._tcp Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.745 [14692.14692] INFO LocalConfigManager: [0xca5e88]: Starting Local Configuration server Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.745 [14692.14692] INFO SampleApp: Starting Local configuration server Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.746 [14692.14692] INFO SampleApp: Connected to UNIX socket client 0xc90818 Dec 06 23:40:56 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:56.819 [14692.14692] INFO SampleApp: Playback volume changed: 75 Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:56 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:57 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:40:57 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:40:57 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.00066109 s, 0.0 kB/s Dec 06 23:40:57 volumio-rpi3 avahi-daemon[750]: Service "Volumio RPi3" (/services/volumio.service) successfully established. Dec 06 23:40:57 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:58 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:40:58 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:40:58 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000572028 s, 0.0 kB/s Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Preparing to save Alsa Options, stopping services first Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPause Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pause Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Found match in Cards Database: setting mixer PCM for card Headphones Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:58 volumio-rpi3 sudo[14720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:58 volumio-rpi3 sudo[14720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Disabling external Volume Control Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Retrieving Cloud Streaming UI Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Preparing to generate the ALSA configuration file Dec 06 23:40:58 volumio-rpi3 sudo[14720]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 06 23:40:58 volumio-rpi3 sudo[14729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 23:40:58 volumio-rpi3 sudo[14729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:58 volumio-rpi3 volumio[1320]: info: Reading ALSA contributions from plugins. Dec 06 23:40:59 volumio-rpi3 sudo[14734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 23:40:59 volumio-rpi3 sudo[14734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:40:59 volumio-rpi3 sudo[14734]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:59 volumio-rpi3 sudo[14729]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:59 volumio-rpi3 sudo[14745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 23:40:59 volumio-rpi3 sudo[14745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 06 23:40:59 volumio-rpi3 qobuz-connect[14692]: 20251206 23:40:59.381 [14692.14692] INFO SampleApp: Stopping Local configuration server Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Getting Tidal Cloud Configuration Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Getting Qobuz Cloud Configuration Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Asking plugin for UI Config Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Getting Spotify Cloud Configuration Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Asking plugin for UI Config Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Saving Spotify Acccount Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Got it Dec 06 23:40:59 volumio-rpi3 volumio[1320]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Discovery: Started advertising with name: Volumio RPi3 Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Asound.conf file unchanged, so no further update is needed Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Output device has changed, restarting MPD Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Output device has changed, restarting Shairport Sync Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:40:59 volumio-rpi3 sudo[14759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 23:40:59 volumio-rpi3 sudo[14759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 sudo[14759]: pam_unix(sudo:session): session closed for user root Dec 06 23:40:59 volumio-rpi3 sudo[14761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 23:40:59 volumio-rpi3 sudo[14761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:40:59 volumio-rpi3 sudo[14771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:59 volumio-rpi3 sudo[14771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 sudo[14773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 23:40:59 volumio-rpi3 sudo[14773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Got Tidal Cloud Configuration Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: Got it Dec 06 23:40:59 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: MPD Permissions set Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 systemd[1]: mpd.service: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 23:41:00 volumio-rpi3 systemd[1]: mpd.service: Consumed 6.889s CPU time. Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 23:41:00 volumio-rpi3 sudo[14781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:00 volumio-rpi3 sudo[14781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:00 volumio-rpi3 sudo[14771]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:00 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:00 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:00 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000291509 s, 0.0 kB/s Dec 06 23:41:00 volumio-rpi3 sudo[14783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 23:41:00 volumio-rpi3 sudo[14783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Starting Shairport Sync Dec 06 23:41:00 volumio-rpi3 sudo[14773]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:00 volumio-rpi3 sudo[14799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 23:41:00 volumio-rpi3 sudo[14799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:00 volumio-rpi3 sudo[14781]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:00 volumio-rpi3 sudo[14802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 23:41:00 volumio-rpi3 sudo[14802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:00 volumio-rpi3 systemd[1]: mpd.socket: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 mpd_monitor.sh[14572]: MPD error: Connection reset by peer Dec 06 23:41:00 volumio-rpi3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 23:41:00 volumio-rpi3 mpd_monitor.sh[20831]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 06 23:41:00 volumio-rpi3 mpd_monitor.sh[14805]: mpd: no process found Dec 06 23:41:00 volumio-rpi3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 23:41:00 volumio-rpi3 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 06 23:41:00 volumio-rpi3 sudo[14783]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Discovery: adding d7ae9f74-3e16-496b-842d-b62f0205880b Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Discovery: Found device Volumio RPi3 Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Discovery: this is already registered, d7ae9f74-3e16-496b-842d-b62f0205880b Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: Discovery: Found device Volumio RPi3 Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:00 volumio-rpi3 qobuz-connect[14692]: 20251206 23:41:00.760 [14692.14692] INFO SampleApp: shat down connection on UNIX socket Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: QobuzConnect: setDeactiveState invoked Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:00 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 23:41:00 volumio-rpi3 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:41:00 volumio-rpi3 systemd[1]: shairport-sync.service: Consumed 1.640s CPU time. Dec 06 23:41:00 volumio-rpi3 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:41:00 volumio-rpi3 systemd[1]: mpd.service: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 23:41:00 volumio-rpi3 systemd[1]: mpd.socket: Deactivated successfully. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 23:41:00 volumio-rpi3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 23:41:00 volumio-rpi3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 23:41:00 volumio-rpi3 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 23:41:00 volumio-rpi3 sudo[14802]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:01 volumio-rpi3 sudo[14745]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:01 volumio-rpi3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 23:41:01 volumio-rpi3 sudo[14799]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Shairport-Sync Started Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Executing endpoint tc_getconfig Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 06 23:41:01 volumio-rpi3 vtcs[14811]: STARTING TidalConnect services, version: 1.5.2.56 Dec 06 23:41:01 volumio-rpi3 vtcs[14811]: STARTED TidalConnect services. Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Executing endpoint tc_connect Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Connecting to TidalConnect Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::servicePushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: Executing endpoint qc_getconfig Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.277 [14815.14815] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.308 [14815.14815] INFO VolumeManager: [0x10905a8]: Setting new playback volume: 75 Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.309 [14815.14815] INFO VolumeManager: [0x10905a8]: Setting new mute state: 0 Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.310 [14815.14815] INFO QobuzConnect: [0x1090f78]: Client initialized! Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.311 [14815.14815] INFO SampleApp: Starting Avahi advertising, name: Volumio RPi3, service name: _qobuz-connect._tcp Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.404 [14815.14815] INFO LocalConfigManager: [0x108fe88]: Starting Local Configuration server Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.404 [14815.14815] INFO SampleApp: Starting Local configuration server Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.405 [14815.14815] INFO SampleApp: Connected to UNIX socket client 0x107a818 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:01 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:01 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000292499 s, 0.0 kB/s Dec 06 23:41:01 volumio-rpi3 qobuz-connect[14815]: 20251206 23:41:01.427 [14815.14815] INFO SampleApp: Playback volume changed: 75 Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:01 volumio-rpi3 sudo[14814]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 23:41:01 volumio-rpi3 sudo[14814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 23:41:01 volumio-rpi3 sudo[14814]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:01 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:02 volumio-rpi3 sudo[14868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 23:41:02 volumio-rpi3 sudo[14868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:02 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:02 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:02 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000310051 s, 0.0 kB/s Dec 06 23:41:02 volumio-rpi3 sudo[14868]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreStateMachine::getcurrentVolume Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: VolumeController:: Volume=100 Mute =false Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:41:02 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:41:03 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:41:03 volumio-rpi3 volumio[1320]: info: TidalConnect service stoped! Dec 06 23:41:03 volumio-rpi3 volumio[1320]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:03 volumio-rpi3 volumio[1320]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 23:41:03 volumio-rpi3 sudo[14890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 23:41:03 volumio-rpi3 sudo[14890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:03 volumio-rpi3 sudo[14890]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:03 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:03 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:03 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000354946 s, 0.0 kB/s Dec 06 23:41:03 volumio-rpi3 volumio[1320]: info: TidalConnect service started! Dec 06 23:41:04 volumio-rpi3 sudo[14900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 06 23:41:04 volumio-rpi3 sudo[14900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:04 volumio-rpi3 sudo[14902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 06 23:41:04 volumio-rpi3 sudo[14902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:04 volumio-rpi3 sudo[14902]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:04 volumio-rpi3 volumio[1320]: info: Internal perms successfully set Dec 06 23:41:04 volumio-rpi3 sudo[14900]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:04 volumio-rpi3 volumio[1320]: info: smb.conf Permissions set Dec 06 23:41:04 volumio-rpi3 sudo[14906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Dec 06 23:41:04 volumio-rpi3 sudo[14906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:04 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:04 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:04 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.0003188 s, 0.0 kB/s Dec 06 23:41:04 volumio-rpi3 nmbd[1095]: [2025/12/06 23:41:04.786205, 0] ../../source3/nmbd/nmbd.c:59(terminate) Dec 06 23:41:04 volumio-rpi3 nmbd[1095]: Got SIGTERM: going down... Dec 06 23:41:04 volumio-rpi3 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Dec 06 23:41:04 volumio-rpi3 systemd[1]: nmbd.service: Deactivated successfully. Dec 06 23:41:04 volumio-rpi3 systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Dec 06 23:41:04 volumio-rpi3 systemd[1]: nmbd.service: Consumed 21.061s CPU time. Dec 06 23:41:04 volumio-rpi3 systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 06 23:41:05 volumio-rpi3 sudo[14919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 23:41:05 volumio-rpi3 sudo[14917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 23:41:05 volumio-rpi3 sudo[14919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:05 volumio-rpi3 sudo[14917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:05 volumio-rpi3 sudo[14917]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:05 volumio-rpi3 sudo[14919]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:05 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:05 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:05 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000303748 s, 0.0 kB/s Dec 06 23:41:05 volumio-rpi3 volumio[1320]: info: TidalConnect service started! Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 23:41:06 volumio-rpi3 sudo[14930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 06 23:41:06 volumio-rpi3 sudo[14930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:06 volumio-rpi3 volumio-remote-updater[775]: Test mode disabled Dec 06 23:41:06 volumio-rpi3 volumio-remote-updater[775]: Alpha mode disabled Dec 06 23:41:06 volumio-rpi3 volumio-remote-updater[775]: Alpha legacy test mode disabled Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 06 23:41:06 volumio-rpi3 sudo[14930]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: Upmpdcli Daemon Started Dec 06 23:41:06 volumio-rpi3 volumio[1320]: info: TidalConnect service started! Dec 06 23:41:06 volumio-rpi3 nmbd[14934]: [2025/12/06 23:41:06.770578, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 06 23:41:06 volumio-rpi3 nmbd[14934]: nmbd version 4.17.8-Raspbian started. Dec 06 23:41:06 volumio-rpi3 nmbd[14934]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 06 23:41:06 volumio-rpi3 nmbd[14934]: [2025/12/06 23:41:06.842089, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 06 23:41:06 volumio-rpi3 nmbd[14934]: started asyncdns process 14949 Dec 06 23:41:06 volumio-rpi3 systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 06 23:41:06 volumio-rpi3 sudo[14906]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:06 volumio-rpi3 sudo[14951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Dec 06 23:41:06 volumio-rpi3 sudo[14951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:06 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:06 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:06 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000675934 s, 0.0 kB/s Dec 06 23:41:07 volumio-rpi3 systemd[1]: Stopping smbd.service - Samba SMB Daemon... Dec 06 23:41:07 volumio-rpi3 systemd[1]: smbd.service: Deactivated successfully. Dec 06 23:41:07 volumio-rpi3 systemd[1]: Stopped smbd.service - Samba SMB Daemon. Dec 06 23:41:07 volumio-rpi3 systemd[1]: smbd.service: Consumed 1.631s CPU time. Dec 06 23:41:07 volumio-rpi3 systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 06 23:41:07 volumio-rpi3 mpd[14857]: 2025-12-06T23:41:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 23:41:08 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:08 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:08 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000344686 s, 0.0 kB/s Dec 06 23:41:08 volumio-rpi3 systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 23:41:08 volumio-rpi3 mpd_monitor.sh[20831]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 06 23:41:08 volumio-rpi3 sudo[14761]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:08 volumio-rpi3 smbd[14964]: [2025/12/06 23:41:08.175978, 0] ../../source3/smbd/server.c:1741(main) Dec 06 23:41:08 volumio-rpi3 smbd[14964]: smbd version 4.17.8-Raspbian started. Dec 06 23:41:08 volumio-rpi3 smbd[14964]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 06 23:41:08 volumio-rpi3 sudo[14458]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:08 volumio-rpi3 volumio[1320]: error: MPD error: The expression evaluated to a falsy value: Dec 06 23:41:08 volumio-rpi3 volumio[1320]: assert.ok(self.idling) Dec 06 23:41:08 volumio-rpi3 volumio[1320]: error: The expression evaluated to a falsy value: Dec 06 23:41:08 volumio-rpi3 volumio[1320]: assert.ok(self.idling) Dec 06 23:41:08 volumio-rpi3 volumio[1320]: error: updateQueue error: null Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 23:41:08 volumio-rpi3 systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 06 23:41:08 volumio-rpi3 volumio-remote-updater[775]: Test mode disabled Dec 06 23:41:08 volumio-rpi3 volumio-remote-updater[775]: Alpha mode disabled Dec 06 23:41:08 volumio-rpi3 volumio-remote-updater[775]: Alpha legacy test mode disabled Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 06 23:41:08 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 06 23:41:09 volumio-rpi3 systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 06 23:41:09 volumio-rpi3 sudo[14951]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:09 volumio-rpi3 volumio[1320]: info: SAMBA Restarted Dec 06 23:41:09 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:09 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:09 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000518747 s, 0.0 kB/s Dec 06 23:41:10 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:10 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:10 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000284425 s, 0.0 kB/s Dec 06 23:41:11 volumio-rpi3 sudo[15001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 23:41:11 volumio-rpi3 sudo[15001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:11 volumio-rpi3 sudo[15003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 23:41:11 volumio-rpi3 sudo[15003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:11 volumio-rpi3 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 23:41:11 volumio-rpi3 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 23:41:11 volumio-rpi3 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 23:41:11 volumio-rpi3 systemd[1]: mpd_monitor.service: Consumed 28.111s CPU time. Dec 06 23:41:11 volumio-rpi3 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 23:41:11 volumio-rpi3 sudo[15001]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:11 volumio-rpi3 sudo[15003]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:11 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:11 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:11 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000282654 s, 0.0 kB/s Dec 06 23:41:11 volumio-rpi3 mpd_monitor.sh[15006]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 23:41:11 volumio-rpi3 volumio[1320]: info: Successfully started MPD Monitor Dec 06 23:41:11 volumio-rpi3 volumio[1320]: info: Successfully started MPD Monitor Dec 06 23:41:11 volumio-rpi3 volumio[1320]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Dec 06 23:41:11 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Dec 06 23:41:11 volumio-rpi3 volumio[1320]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') Dec 06 23:41:12 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:12 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:12 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000650413 s, 0.0 kB/s Dec 06 23:41:13 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:13 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:13 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000240468 s, 0.0 kB/s Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::Reload Ui Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: Wizard terminated Successfully Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumiosetStartupVolume Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 23:41:14 volumio-rpi3 sudo[15030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 23:41:14 volumio-rpi3 sudo[15030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:14 volumio-rpi3 sudo[15030]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:14 volumio-rpi3 sudo[15032]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 23:41:14 volumio-rpi3 sudo[15032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:14 volumio-rpi3 sudo[15032]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:14 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:14 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:14 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.00028604 s, 0.0 kB/s Dec 06 23:41:14 volumio-rpi3 volumio[1320]: verbose: New Socket.io Connection to 192.168.7.247 from 192.168.7.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 06 23:41:14 volumio-rpi3 sudo[15042]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 23:41:14 volumio-rpi3 sudo[15042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:14 volumio-rpi3 sudo[15042]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:14 volumio-rpi3 sudo[15044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 23:41:14 volumio-rpi3 sudo[15044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 23:41:14 volumio-rpi3 sudo[15044]: pam_unix(sudo:session): session closed for user root Dec 06 23:41:14 volumio-rpi3 volumio[1320]: verbose: New Socket.io Connection to 192.168.7.247 from 192.168.7.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:14 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: Listing playlists Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 06 23:41:15 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 23:41:15 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:15 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:15 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000161406 s, 0.0 kB/s Dec 06 23:41:16 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:16 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:16 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000179166 s, 0.0 kB/s Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: Received Get System Info Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: Discovery: Getting this device information Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioSeek Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreStateMachine::seek Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: TRACKBLOCK {"uri":"mnt/NAS/Video/High Res Music Archive/Vicci Martinez/Vicci/I Want Your Kiss.m4a","service":"mpd","name":"I Want Your Kiss","artist":"Vicci Martinez","album":"Vicci [HD] (M4a)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=304&web=Vicci%20Martinez/Vicci%20%5BHD%5D%20%20(M4a)/extralarge&path=%2FNAS%2FVideo%2FHigh%20Res%20Music%20Archive%2FVicci%20Martinez%2FVicci&metadata=false","duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","trackType":"m4a","channels":2} Dec 06 23:41:17 volumio-rpi3 volumio[1320]: info: CoreStateMachine::startPlaybackTimer Dec 06 23:41:18 volumio-rpi3 kernel: hwmon hwmon1: Voltage normalised Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: ControllerMpd::seek Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CoreStateMachine::pushState Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPushState Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output update for this device Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: MRS: Pushing multiroomSync output Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 7 Dec 06 23:41:18 volumio-rpi3 volumio[1320]: error: [2@0] {seek} Bad song index Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: FusionDsp - Volumio is not playing Dec 06 23:41:18 volumio-rpi3 volumio[1320]: info: FusionDsp - Clipped samples monitor stopped Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000155312 s, 0.0 kB/s Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:18 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000357602 s, 0.0 kB/s Dec 06 23:41:19 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:19 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:19 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000161978 s, 0.0 kB/s Dec 06 23:41:20 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:20 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:20 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000158333 s, 0.0 kB/s Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPrevious Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CoreStateMachine::previous Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CoreStateMachine::serviceClearAddPlay Dec 06 23:41:21 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 06 23:41:21 volumio-rpi3 volumio[1320]: error: WARNING: No clearAddPlayTracks method for service mpd Dec 06 23:41:21 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:21 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:21 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000356404 s, 0.0 kB/s Dec 06 23:41:23 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:23 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:23 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000157499 s, 0.0 kB/s Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPrevious Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::previous Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::serviceClearAddPlay Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 06 23:41:23 volumio-rpi3 volumio[1320]: error: WARNING: No clearAddPlayTracks method for service mpd Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPrevious Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::previous Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreStateMachine::serviceClearAddPlay Dec 06 23:41:23 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 06 23:41:23 volumio-rpi3 volumio[1320]: error: WARNING: No clearAddPlayTracks method for service mpd Dec 06 23:41:24 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:24 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:24 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.0003313 s, 0.0 kB/s Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPrevious Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::previous Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::serviceClearAddPlay Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 06 23:41:24 volumio-rpi3 volumio[1320]: error: WARNING: No clearAddPlayTracks method for service mpd Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioPrevious Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::previous Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::updateTrackBlock Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrackBlock Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreStateMachine::serviceClearAddPlay Dec 06 23:41:24 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 06 23:41:24 volumio-rpi3 volumio[1320]: error: WARNING: No clearAddPlayTracks method for service mpd Dec 06 23:41:25 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:25 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:25 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000157551 s, 0.0 kB/s Dec 06 23:41:26 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:26 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:26 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000162968 s, 0.0 kB/s Dec 06 23:41:27 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:27 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:27 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000171926 s, 0.0 kB/s Dec 06 23:41:28 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:28 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:28 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000157811 s, 0.0 kB/s Dec 06 23:41:29 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:29 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:29 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.00016651 s, 0.0 kB/s Dec 06 23:41:30 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:30 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:30 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000157759 s, 0.0 kB/s Dec 06 23:41:31 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:31 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:31 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000320311 s, 0.0 kB/s Dec 06 23:41:32 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:32 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:32 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000517288 s, 0.0 kB/s Dec 06 23:41:32 volumio-rpi3 kernel: hwmon hwmon1: Undervoltage detected! Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: Received Get System Version Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: Received Get System Info Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: Discovery: Getting this device information Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::volumioGetState Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CorePlayQueue::getTrack 2 Dec 06 23:41:33 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 23:41:33 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:33 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:33 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000274009 s, 0.0 kB/s Dec 06 23:41:34 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:34 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:34 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000513695 s, 0.0 kB/s Dec 06 23:41:35 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:35 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:35 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000337707 s, 0.0 kB/s Dec 06 23:41:36 volumio-rpi3 volumio[1320]: 0+0 records in Dec 06 23:41:36 volumio-rpi3 volumio[1320]: 0+0 records out Dec 06 23:41:36 volumio-rpi3 volumio[1320]: 0 bytes copied, 0.000159009 s, 0.0 kB/s Dec 06 23:41:36 volumio-rpi3 kernel: hwmon hwmon1: Voltage normalised Dec 06 23:41:37 volumio-rpi3 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 06 23:41:37 volumio-rpi3 volumio[1320]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 06 23:41:37 volumio-rpi3 volumio[1320]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 23:41:37 volumio-rpi3 volumio[1320]: TypeError: Cannot read properties of undefined (reading 'then') Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at Socket.emit (node:events:514:28) Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 06 23:41:37 volumio-rpi3 volumio[1320]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 06 23:41:37 volumio-rpi3 volumio[1320]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 23:41:39 volumio-rpi3 sudo[15242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 23:40' Dec 06 23:41:39 volumio-rpi3 sudo[15242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"