Oct 11 18:34:00 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:00 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:01 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:01 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:02 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:02 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:03 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:03 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:03 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:03 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:04 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:04 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:05 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:05 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:06 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:07 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:08 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:08 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:09 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:09 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:09 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:09 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:10 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:10 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:11 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:11 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:12 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:12 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:13 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:13 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:13 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:13 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:14 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:14 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:15 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:15 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:16 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:16 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:17 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:17 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:18 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:19 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:19 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:20 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:20 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:21 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:21 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:22 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:22 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:23 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:23 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:23 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:23 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:24 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:24 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:25 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:25 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:26 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:26 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:27 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:27 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:28 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:28 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:30 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:31 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:32 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:33 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:33 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:33 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:33 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:34 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:34 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:35 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:35 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:36 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:36 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:37 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:37 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:38 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:39 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:39 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:39 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:39 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:40 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:40 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:41 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:41 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:42 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:42 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:43 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:43 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:43 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:43 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:44 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:45 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:45 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:46 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:46 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:47 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:48 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:48 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:49 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:49 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:49 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:49 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:50 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:50 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:51 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:51 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:52 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:52 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:53 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:53 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:54 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:55 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:55 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:56 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:57 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:57 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:58 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:58 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:34:59 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:34:59 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:00 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:00 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:00 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:00 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:01 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:01 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:02 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:02 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:03 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:03 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:03 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:03 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:04 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:04 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:05 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:05 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:06 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:07 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:08 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:08 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:09 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:09 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:09 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:09 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:10 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:10 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:11 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:11 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:12 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:12 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:13 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:13 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:13 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:13 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:14 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:14 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:15 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:15 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:16 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:16 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:17 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:17 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:18 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:19 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:19 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:20 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:20 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:21 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:21 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:22 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:22 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:23 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:23 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:23 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:23 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:24 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:24 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:25 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:25 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:26 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:26 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:27 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:27 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:28 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:28 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:30 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:31 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:32 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:33 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:33 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:33 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:33 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:34 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:34 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:35 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:35 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:36 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:36 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:37 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:37 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:38 volumio kernel: usb 1-1.2: USB disconnect, device number 3 Oct 11 18:35:38 volumio volumio[1219]: info: Oct 11 18:35:38 volumio volumio[1219]: ---------------------------- MPD announces state update: player Oct 11 18:35:38 volumio volumio[1219]: info: ControllerMpd::getState Oct 11 18:35:38 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:35:38 volumio volumio[1219]: error: MPD returned error for command status: Failed to open audio output Oct 11 18:35:38 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 11 18:35:38 volumio volumio[1219]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:35:38 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 11 18:35:38 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:35:38 volumio volumio[1219]: info: sendMpdCommand clearerror took 1 milliseconds Oct 11 18:35:38 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 18:35:38 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:35:38 volumio volumio[1219]: info: ControllerMpd::pushState Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 11 18:35:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:38 volumio volumio[1219]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144724,"duration":288,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3270 Kbps","isStreaming":false,"title":"file?uid=8435324&eid=214875308&fmt=7&profile=raw&app_id=539451548&cid=3377869&etsp=1791714788&hmac=XOVqEp3LaNklnmkaxAZQRVGdzlM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8435324&eid=214875308&fmt=7&profile=raw&app_id=539451548&cid=3377869&etsp=1791714788&hmac=XOVqEp3LaNklnmkaxAZQRVGdzlM","trackType":"qobuz"} Oct 11 18:35:38 volumio volumio[1219]: verbose: CURRENT POSITION 5 Oct 11 18:35:38 volumio volumio[1219]: info: CoreStateMachine::syncState stateService pause Oct 11 18:35:38 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:35:38 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 11 18:35:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 11 18:35:38 volumio volumio[1219]: info: MRS: Pushing multiroomSync output update for this device Oct 11 18:35:38 volumio volumio[1219]: info: MRS: Pushing multiroomSync output Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:38 volumio volumio[1219]: info: ------------------------------ 15ms Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:38 volumio volumio[1219]: info: Oct 11 18:35:38 volumio volumio[1219]: ---------------------------- USB Audio Device Detached Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:38 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 11 18:35:39 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:39 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:39 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:39 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:40 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:40 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:41 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:41 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:42 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:42 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:43 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:43 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:43 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:43 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:44 volumio kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd Oct 11 18:35:44 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=2009, bcdDevice=76.83 Oct 11 18:35:44 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 11 18:35:44 volumio kernel: usb 1-1.2: Product: DIYINHK USB Audio 2.0 Oct 11 18:35:44 volumio kernel: usb 1-1.2: Manufacturer: DIYINHK Oct 11 18:35:44 volumio kernel: input: DIYINHK DIYINHK USB Audio 2.0 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.3/0003:20B1:2009.0002/input/input5 Oct 11 18:35:44 volumio kernel: hid-generic 0003:20B1:2009.0002: input,hidraw0: USB HID v1.10 Device [DIYINHK DIYINHK USB Audio 2.0] on usb-0000:01:00.0-1.2/input3 Oct 11 18:35:44 volumio systemd[16967]: Reached target sound.target - Sound Card. Oct 11 18:35:44 volumio volumio[1219]: info: Oct 11 18:35:44 volumio volumio[1219]: ---------------------------- USB Audio Device Attached Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::Close All Modals sent Oct 11 18:35:44 volumio volumio[1219]: info: Preparing to save Alsa Options, stopping services first Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioPause Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::pause Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::servicePause Oct 11 18:35:44 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::servicePause Oct 11 18:35:44 volumio volumio[1219]: info: [1791711344563] ControllerQobuz::pause Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 18:35:44 volumio volumio[1219]: info: ControllerMpd::pause Oct 11 18:35:44 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand pause Oct 11 18:35:44 volumio volumio[1219]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DIYINHK USB Audio 2.0","alsacard":"D20"},"i2s":false} Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:44 volumio volumio[1219]: info: Setting mixer DIYINHK USB Audio 2.0 for card DIYINHK USB Audio 2.0 Oct 11 18:35:44 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio vtcs[3510]: [2026-10-11 18:35:44.626] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 11 18:35:44 volumio vtcs[3510]: [2026-10-11 18:35:44.627] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 11 18:35:44 volumio sudo[28747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:44 volumio sudo[28747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 18:35:44 volumio volumio[1219]: info: Updating Volume Controller Parameters: Device: 5 Name: DIYINHK USB Audio 2.0 Mixer: DIYINHK USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 11 18:35:44 volumio volumio[1219]: info: Disabling external Volume Control Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 18:35:44 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 11 18:35:44 volumio volumio[1219]: info: Preparing to generate the ALSA configuration file Oct 11 18:35:44 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 11 18:35:44 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 11 18:35:44 volumio systemd[1]: vtcs.service: Consumed 35.721s CPU time. Oct 11 18:35:44 volumio sudo[28747]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:44 volumio sudo[28756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:44 volumio sudo[28756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio volumio[1219]: info: Oct 11 18:35:44 volumio volumio[1219]: ---------------------------- MPD announces state update: player Oct 11 18:35:44 volumio volumio[1219]: info: sendMpdCommand pause took 211 milliseconds Oct 11 18:35:44 volumio volumio[1219]: info: ControllerMpd::getState Oct 11 18:35:44 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:35:44 volumio volumio[1219]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 18:35:44 volumio volumio[1219]: info: Reading ALSA contributions from plugins. Oct 11 18:35:44 volumio sudo[28763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:44 volumio volumio[1219]: info: sendMpdCommand status took 22 milliseconds Oct 11 18:35:44 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 11 18:35:44 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:35:44 volumio sudo[28763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio sudo[28756]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:44 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 18:35:44 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:35:44 volumio volumio[1219]: info: ControllerMpd::pushState Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 11 18:35:44 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:44 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":145976,"duration":288,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3259 Kbps","isStreaming":false,"title":"file?uid=8435324&eid=214875308&fmt=7&profile=raw&app_id=539451548&cid=3377869&etsp=1791714788&hmac=XOVqEp3LaNklnmkaxAZQRVGdzlM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8435324&eid=214875308&fmt=7&profile=raw&app_id=539451548&cid=3377869&etsp=1791714788&hmac=XOVqEp3LaNklnmkaxAZQRVGdzlM","trackType":"qobuz"} Oct 11 18:35:44 volumio volumio[1219]: verbose: CURRENT POSITION 5 Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus pause Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 11 18:35:44 volumio volumio[1219]: info: MRS: Pushing multiroomSync output update for this device Oct 11 18:35:44 volumio volumio[1219]: info: MRS: Pushing multiroomSync output Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio volumio[1219]: info: ------------------------------ 46ms Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio sudo[28763]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:44 volumio sudo[28767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:44 volumio sudo[28767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:44 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 11 18:35:44 volumio qobuz-connect[3479]: 20261011 18:35:44.878 [3479.3479] INFO SampleApp: Stopping Local configuration server Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 18:35:44 volumio volumio[1219]: info: VolumeController:: Volume=100 Mute =false Oct 11 18:35:44 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 11 18:35:44 volumio volumio[1219]: info: MRS: Pushing multiroomSync output update for this device Oct 11 18:35:44 volumio volumio[1219]: info: MRS: Pushing multiroomSync output Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio volumio[1219]: info: Asound.conf file unchanged, so no further update is needed Oct 11 18:35:44 volumio volumio[1219]: info: Output device has changed, restarting MPD Oct 11 18:35:44 volumio volumio[1219]: info: Output device has changed, restarting Shairport Sync Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:44 volumio sudo[28776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:35:44 volumio sudo[28776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio sudo[28776]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:44 volumio sudo[28778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:35:44 volumio sudo[28778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:44 volumio volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:35:44 volumio volumio[1219]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:44 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:44 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 11 18:35:45 volumio sudo[28788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 18:35:45 volumio volumio[1219]: info: MPD Permissions set Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: Starting Shairport Sync Oct 11 18:35:45 volumio sudo[28788]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio volumio[1219]: info: Preparing to generate the ALSA configuration file Oct 11 18:35:45 volumio sudo[28798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:45 volumio sudo[28798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 18:35:45 volumio volumio[1219]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 18:35:45 volumio volumio[1219]: info: Reading ALSA contributions from plugins. Oct 11 18:35:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 18:35:45 volumio systemd[1]: mpd.service: Consumed 12min 41.942s CPU time. Oct 11 18:35:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 18:35:45 volumio volumio[1219]: info: Asound.conf file written Oct 11 18:35:45 volumio sudo[28806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:35:45 volumio sudo[28806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 18:35:45 volumio sudo[28812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28812]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: info: Output device has changed, restarting MPD Oct 11 18:35:45 volumio sudo[28798]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28790]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:35:45 volumio sudo[28819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28819]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio volumio[1219]: info: Output device has changed, restarting Shairport Sync Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 18:35:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:45 volumio systemd[1]: shairport-sync.service: Consumed 5min 22.390s CPU time. Oct 11 18:35:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:45 volumio sudo[28821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:45 volumio sudo[28806]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:35:45 volumio sudo[28824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:35:45 volumio volumio[1219]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:45 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 18:35:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 18:35:45 volumio sudo[28849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 11 18:35:45 volumio volumio[1219]: info: MPD Permissions set Oct 11 18:35:45 volumio volumio[1219]: info: Shairport-Sync Started Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio sudo[28862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:45 volumio sudo[28862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: Starting Shairport Sync Oct 11 18:35:45 volumio volumio[1219]: info: Preparing to generate the ALSA configuration file Oct 11 18:35:45 volumio volumio[1219]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 18:35:45 volumio volumio[1219]: info: Reading ALSA contributions from plugins. Oct 11 18:35:45 volumio sudo[28862]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio volumio[1219]: info: Asound.conf file written Oct 11 18:35:45 volumio sudo[28849]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:35:45 volumio sudo[28871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28851]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 18:35:45 volumio sudo[28877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:45 volumio sudo[28875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 18:35:45 volumio sudo[28877]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:45 volumio sudo[28869]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 18:35:45 volumio sudo[28869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 11 18:35:45 volumio sudo[28869]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 18:35:45 volumio volumio[1219]: info: Output device has changed, restarting MPD Oct 11 18:35:45 volumio volumio[1219]: info: Output device has changed, restarting Shairport Sync Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:45 volumio sudo[28871]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:35:45 volumio sudo[28892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:35:45 volumio volumio[1219]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio sudo[28892]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:35:45 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:45 volumio sudo[28890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 18:35:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 18:35:45 volumio sudo[28917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:45 volumio sudo[28917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: info: MPD Permissions set Oct 11 18:35:45 volumio volumio[1219]: info: Shairport-Sync Started Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio sudo[28927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:45 volumio sudo[28927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 18:35:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:45 volumio volumio[1219]: info: Starting Shairport Sync Oct 11 18:35:45 volumio sudo[28936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:35:45 volumio sudo[28936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio sudo[28927]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28902]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28917]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:45 volumio sudo[28939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:45 volumio sudo[28939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 18:35:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 18:35:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:45 volumio sudo[28934]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 18:35:45 volumio sudo[28934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 11 18:35:45 volumio sudo[28934]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:46 volumio sudo[28936]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:46 volumio volumio[1219]: info: Shairport-Sync Started Oct 11 18:35:46 volumio volumio[1219]: (node:1219) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 18:35:46 volumio volumio[1219]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 11 18:35:46 volumio volumio[1219]: (node:1219) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 18:35:46 volumio volumio[1219]: (node:1219) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 18:35:46 volumio volumio[1219]: (node:1219) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 18:35:46 volumio volumio[1219]: (node:1219) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 11 18:35:46 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:46 volumio qobuz-connect[3479]: 20261011 18:35:46.874 [3479.3479] INFO SampleApp: shat down connection on UNIX socket Oct 11 18:35:46 volumio volumio[1219]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 11 18:35:46 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:46 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:46 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 11 18:35:46 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 11 18:35:46 volumio systemd[1]: qobuz-connect.service: Consumed 1.211s CPU time. Oct 11 18:35:46 volumio sudo[28821]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:46 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 11 18:35:46 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Oct 11 18:35:46 volumio sudo[28875]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:46 volumio sudo[28939]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:46 volumio sudo[28767]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio volumio[1219]: info: Executing endpoint qc_getconfig Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.028 [28959.28959] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 11 18:35:47 volumio volumio[1219]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 11 18:35:47 volumio volumio[1219]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.036 [28959.28959] INFO VolumeManager: [0x10f09c8]: Setting new playback volume: 75 Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.036 [28959.28959] INFO VolumeManager: [0x10f09c8]: Setting new mute state: 0 Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.036 [28959.28959] INFO AudioStreamManager: [0x10f0720]: Setting new audio download buffer size: 1048576 Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.046 [28959.28959] INFO QobuzConnect: [0x10f1290]: Client initialized! Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.046 [28959.28959] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.062 [28959.28959] INFO LocalConfigManager: [0x10f0448]: Starting Local Configuration server Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.062 [28959.28959] INFO SampleApp: Starting Local configuration server Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.062 [28959.28959] INFO SampleApp: Connected to UNIX socket client 0x10db818 Oct 11 18:35:47 volumio volumio[1219]: info: Oct 11 18:35:47 volumio volumio[1219]: ---------------------------- USB Audio Device Detached Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.181 [28959.28959] INFO SampleApp: Playback volume changed: 75 Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:47 volumio kernel: usb 1-1.2: new high-speed USB device number 5 using xhci_hcd Oct 11 18:35:47 volumio mpd[28944]: 2026-10-11T18:35:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 18:35:47 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 11 18:35:47 volumio sudo[28778]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio sudo[28824]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio sudo[28890]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio volumio[1219]: error: MPD error: The expression evaluated to a falsy value: Oct 11 18:35:47 volumio volumio[1219]: assert.ok(self.idling) Oct 11 18:35:47 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=2009, bcdDevice=76.83 Oct 11 18:35:47 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 11 18:35:47 volumio kernel: usb 1-1.2: Product: DIYINHK USB Audio 2.0 Oct 11 18:35:47 volumio kernel: usb 1-1.2: Manufacturer: DIYINHK Oct 11 18:35:47 volumio volumio[1219]: error: The expression evaluated to a falsy value: Oct 11 18:35:47 volumio volumio[1219]: assert.ok(self.idling) Oct 11 18:35:47 volumio volumio[1219]: error: MPD error: The expression evaluated to a falsy value: Oct 11 18:35:47 volumio volumio[1219]: assert.ok(self.idling) Oct 11 18:35:47 volumio volumio[1219]: error: The expression evaluated to a falsy value: Oct 11 18:35:47 volumio volumio[1219]: assert.ok(self.idling) Oct 11 18:35:47 volumio volumio[1219]: error: updateQueue error: null Oct 11 18:35:47 volumio kernel: input: DIYINHK DIYINHK USB Audio 2.0 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.3/0003:20B1:2009.0003/input/input6 Oct 11 18:35:47 volumio kernel: hid-generic 0003:20B1:2009.0003: input,hidraw0: USB HID v1.10 Device [DIYINHK DIYINHK USB Audio 2.0] on usb-0000:01:00.0-1.2/input3 Oct 11 18:35:47 volumio volumio[1219]: info: Oct 11 18:35:47 volumio volumio[1219]: ---------------------------- USB Audio Device Attached Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::Close All Modals sent Oct 11 18:35:47 volumio volumio[1219]: info: Preparing to save Alsa Options, stopping services first Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioPause Oct 11 18:35:47 volumio volumio[1219]: info: CoreStateMachine::pause Oct 11 18:35:47 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:35:47 volumio volumio[1219]: info: CoreStateMachine::servicePause Oct 11 18:35:47 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::servicePause Oct 11 18:35:47 volumio volumio[1219]: info: [1791711347669] ControllerQobuz::pause Oct 11 18:35:47 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 18:35:47 volumio volumio[1219]: info: ControllerMpd::pause Oct 11 18:35:47 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand pause Oct 11 18:35:47 volumio volumio[1219]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DIYINHK USB Audio 2.0","alsacard":"D20"},"i2s":false} Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:47 volumio volumio[1219]: info: Setting mixer DIYINHK USB Audio 2.0 for card DIYINHK USB Audio 2.0 Oct 11 18:35:47 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:47 volumio sudo[28995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:47 volumio sudo[28995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 18:35:47 volumio volumio[1219]: info: Updating Volume Controller Parameters: Device: 5 Name: DIYINHK USB Audio 2.0 Mixer: DIYINHK USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 11 18:35:47 volumio volumio[1219]: info: Disabling external Volume Control Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 18:35:47 volumio volumio[1219]: info: Preparing to generate the ALSA configuration file Oct 11 18:35:47 volumio sudo[28995]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio sudo[29003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:47 volumio sudo[29003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:47 volumio volumio[1219]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 18:35:47 volumio volumio[1219]: info: Reading ALSA contributions from plugins. Oct 11 18:35:47 volumio volumio[1219]: info: sendMpdCommand pause took 182 milliseconds Oct 11 18:35:47 volumio sudo[29010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:47 volumio sudo[29010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:47 volumio volumio[1219]: info: TidalConnect service stoped! Oct 11 18:35:47 volumio volumio[1219]: info: TidalConnect service stoped! Oct 11 18:35:47 volumio volumio[1219]: info: VolumeController:: Volume=100 Mute =false Oct 11 18:35:47 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 11 18:35:47 volumio volumio[1219]: info: MRS: Pushing multiroomSync output update for this device Oct 11 18:35:47 volumio volumio[1219]: info: MRS: Pushing multiroomSync output Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:47 volumio sudo[29010]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio sudo[29003]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:47 volumio sudo[29017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:47 volumio sudo[29017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:35:47 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 11 18:35:47 volumio qobuz-connect[28959]: 20261011 18:35:47.955 [28959.28959] INFO SampleApp: Stopping Local configuration server Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 11 18:35:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 18:35:47 volumio volumio[1219]: info: Asound.conf file unchanged, so no further update is needed Oct 11 18:35:47 volumio volumio[1219]: info: Output device has changed, restarting MPD Oct 11 18:35:48 volumio volumio[1219]: info: Output device has changed, restarting Shairport Sync Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:48 volumio sudo[29031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:35:48 volumio sudo[29031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio sudo[29031]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio sudo[29033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:35:48 volumio sudo[29033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:35:48 volumio volumio[1219]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: QobuzConnect: setDeactiveState invoked Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 11 18:35:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 11 18:35:48 volumio sudo[29043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:48 volumio sudo[29043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio sudo[29045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 18:35:48 volumio sudo[29045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio volumio[1219]: info: MPD Permissions set Oct 11 18:35:48 volumio volumio[1219]: info: TidalConnect service stoped! Oct 11 18:35:48 volumio volumio[1219]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 11 18:35:48 volumio volumio[1219]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 11 18:35:48 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 18:35:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 18:35:48 volumio systemd[1]: mpd.service: Consumed 1.531s CPU time. Oct 11 18:35:48 volumio sudo[29053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 18:35:48 volumio sudo[29053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 11 18:35:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 18:35:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:35:48 volumio volumio[1219]: info: Starting Shairport Sync Oct 11 18:35:48 volumio sudo[29063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 11 18:35:48 volumio sudo[29063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio sudo[29065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:35:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 18:35:48 volumio sudo[29065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:35:48 volumio volumio[1219]: info: TidalConnect service stoped! Oct 11 18:35:48 volumio sudo[29053]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio sudo[29045]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio sudo[29070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 18:35:48 volumio sudo[29070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:35:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 18:35:48 volumio sudo[29043]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio volumio[1219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:35:48 volumio volumio[1219]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 11 18:35:48 volumio volumio[1219]: Job for vtcs.service canceled. Oct 11 18:35:48 volumio volumio[1219]: at ChildProcess.exithandler (node:child_process:421:12) Oct 11 18:35:48 volumio volumio[1219]: at ChildProcess.emit (node:events:514:28) Oct 11 18:35:48 volumio volumio[1219]: at maybeClose (node:internal/child_process:1105:16) Oct 11 18:35:48 volumio volumio[1219]: at Socket. (node:internal/child_process:457:11) Oct 11 18:35:48 volumio volumio[1219]: at Socket.emit (node:events:514:28) Oct 11 18:35:48 volumio volumio[1219]: at Pipe. (node:net:337:12) { Oct 11 18:35:48 volumio volumio[1219]: code: 1, Oct 11 18:35:48 volumio volumio[1219]: killed: false, Oct 11 18:35:48 volumio volumio[1219]: signal: null, Oct 11 18:35:48 volumio volumio[1219]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 11 18:35:48 volumio volumio[1219]: stdout: '', Oct 11 18:35:48 volumio volumio[1219]: stderr: 'Job for vtcs.service canceled.\n' Oct 11 18:35:48 volumio volumio[1219]: } Oct 11 18:35:48 volumio volumio[1219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:35:48 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 11 18:35:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 18:35:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:48 volumio systemd[1]: shairport-sync.service: Consumed 1.536s CPU time. Oct 11 18:35:48 volumio sudo[29063]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:35:48 volumio sudo[29065]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio sudo[29068]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 18:35:48 volumio sudo[29068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 11 18:35:48 volumio sudo[29068]: pam_unix(sudo:session): session closed for user root Oct 11 18:35:48 volumio sudo[29114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 18:34' Oct 11 18:35:48 volumio sudo[29114]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"