-- Logs begin at Mon 2025-06-23 21:53:32 +07, end at Wed 2025-06-25 21:49:39 +07. -- Jun 25 21:48:01 volumio volumio[13046]: info: TidalConnect service started! Jun 25 21:48:01 volumio volumio[13046]: [Metrics] CommandRouter: 21s 985.37ms Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumiosetStartupVolume Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:01 volumio volumio[13046]: info: VolumeController:: Setting startup Volume 100 Jun 25 21:48:01 volumio volumio[13046]: info: VolumeController::SetAlsaVolume100 Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::Close All Modals sent Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::Close All Modals sent Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioPlay Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::play index undefined Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::startPlaybackTimer Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::clearAddPlayTracks NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand stop took 0 milliseconds Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 21:48:01 volumio volumio[13046]: info: Jun 25 21:48:01 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:01 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand clear took 1 milliseconds Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" Jun 25 21:48:01 volumio volumio[13046]: error: updateQueue error: null Jun 25 21:48:01 volumio volumio[13046]: info: ------------------------------ 2ms Jun 25 21:48:01 volumio volumio[13046]: info: Jun 25 21:48:01 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:01 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" took 1 milliseconds Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand play Jun 25 21:48:01 volumio volumio[13046]: info: ------------------------------ 1ms Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand play took 1 milliseconds Jun 25 21:48:01 volumio volumio[13046]: info: Jun 25 21:48:01 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:01 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:01 volumio volumio[13046]: info: Jun 25 21:48:01 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand status took 4 milliseconds Jun 25 21:48:01 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand clearerror took 4 milliseconds Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand status took 3 milliseconds Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:01 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:01 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::syncState stateService pause Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: ------------------------------ 22ms Jun 25 21:48:01 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 25 21:48:01 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:01 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:01 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::syncState stateService pause Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:01 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:01 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:01 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:01 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:01 volumio volumio[13046]: info: ------------------------------ 55ms Jun 25 21:48:02 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 25 21:48:02 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 21:48:02 volumio volumio[13046]: No protocol specified Jun 25 21:48:02 volumio volumio[13046]: xcb_connection_has_error() returned true Jun 25 21:48:02 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPlay Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::play index undefined Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::startPlaybackTimer Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::clearAddPlayTracks NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand stop took 1 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 21:48:04 volumio volumio[13046]: info: Jun 25 21:48:04 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:04 volumio volumio[13046]: info: Jun 25 21:48:04 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:04 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand clear took 1 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" Jun 25 21:48:04 volumio volumio[13046]: error: updateQueue error: null Jun 25 21:48:04 volumio volumio[13046]: info: Jun 25 21:48:04 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:04 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand status took 2 milliseconds Jun 25 21:48:04 volumio volumio[13046]: info: ------------------------------ 3ms Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" took 1 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand play Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 25 21:48:04 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState stateService stop Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: No code Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: ------------------------------ 37ms Jun 25 21:48:04 volumio volumio[13046]: info: Jun 25 21:48:04 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:04 volumio volumio[13046]: info: ------------------------------ 36ms Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand play took 35 milliseconds Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:04 volumio volumio[13046]: info: Jun 25 21:48:04 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand status took 13 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand status took 6 milliseconds Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand clearerror took 4 milliseconds Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:04 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState stateService pause Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: ------------------------------ 28ms Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand clearerror took 14 milliseconds Jun 25 21:48:04 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 25 21:48:04 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:04 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:04 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState stateService pause Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:04 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:04 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:04 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:04 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:04 volumio volumio[13046]: info: ------------------------------ 34ms Jun 25 21:48:05 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 21:48:05 volumio volumio[13046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 25 21:48:05 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:05 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:08 volumio volumio[13046]: info: BOOT COMPLETED Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::volumioPlay Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::play index undefined Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::startPlaybackTimer Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::clearAddPlayTracks NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 21:48:09 volumio volumio[13046]: info: Jun 25 21:48:09 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand stop took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 21:48:09 volumio volumio[13046]: info: Jun 25 21:48:09 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:09 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand status took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand clear took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" Jun 25 21:48:09 volumio volumio[13046]: error: updateQueue error: null Jun 25 21:48:09 volumio volumio[13046]: info: Jun 25 21:48:09 volumio volumio[13046]: ---------------------------- MPD announces system playlist update Jun 25 21:48:09 volumio volumio[13046]: info: Ignoring MPD Status Update Jun 25 21:48:09 volumio volumio[13046]: info: ------------------------------ 3ms Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand add "NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac" took 1 milliseconds Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand play Jun 25 21:48:09 volumio volumio[13046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 25 21:48:09 volumio volumio[13046]: info: ------------------------------ 7ms Jun 25 21:48:09 volumio volumio[13046]: info: Jun 25 21:48:09 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:09 volumio volumio[13046]: info: ------------------------------ 4ms Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand play took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:09 volumio volumio[13046]: info: Jun 25 21:48:09 volumio volumio[13046]: ---------------------------- MPD announces state update: player Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand status took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: ControllerMpd::getState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand status took 1 milliseconds Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseState Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:09 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:09 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::syncState stateService play Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:09 volumio volumio[13046]: info: ------------------------------ 5ms Jun 25 21:48:09 volumio volumio[13046]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 25 21:48:09 volumio volumio[13046]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:09 volumio volumio[13046]: info: ControllerMpd::pushState Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::servicePushState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1171 Kbps","isStreaming":false,"title":"The Carol (희진, 현진, 하슬)","artist":"이달의 소녀","album":"HaSeul","uri":"NAS/Truenas/The Carol (희진, 현진, 하슬)_이달의 소녀_HaSeul.flac","trackType":"flac"} Jun 25 21:48:09 volumio volumio[13046]: verbose: CURRENT POSITION 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::syncState stateService play Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::syncState currentStatus play Jun 25 21:48:09 volumio volumio[13046]: info: Received an update from plugin. extracting info from payload Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:09 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:09 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreStateMachine::pushState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:09 volumio volumio[13046]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:09 volumio volumio[13046]: info: MRS: Pushing multiroomSync output Jun 25 21:48:09 volumio volumio[13046]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:09 volumio volumio[13046]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:09 volumio volumio[13046]: info: ------------------------------ 31ms Jun 25 21:48:09 volumio volumio[13046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 21:48:09 volumio volumio[13046]: TypeError [ERR_INVALID_ARG_TYPE]: The "chunk" argument must be of type string or an instance of Buffer or Uint8Array. Received undefined Jun 25 21:48:09 volumio volumio[13046]: at new NodeError (internal/errors.js:322:7) Jun 25 21:48:09 volumio volumio[13046]: at SerialPort.Writable.write (internal/streams/writable.js:285:13) Jun 25 21:48:09 volumio volumio[13046]: at SerialPort.write (/data/plugins/system_controller/serialampcontroller/node_modules/@serialport/stream/lib/index.js:307:21) Jun 25 21:48:09 volumio volumio[13046]: at serialampcontroller.sendCommand (/data/plugins/system_controller/serialampcontroller/index.js:478:19) Jun 25 21:48:09 volumio volumio[13046]: at Socket. (/data/plugins/system_controller/serialampcontroller/index.js:60:22) Jun 25 21:48:09 volumio volumio[13046]: at Socket.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:48:09 volumio volumio[13046]: at Socket.onevent (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/socket.js:278:10) Jun 25 21:48:09 volumio volumio[13046]: at Socket.onpacket (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/socket.js:236:12) Jun 25 21:48:09 volumio volumio[13046]: at Manager. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) Jun 25 21:48:09 volumio volumio[13046]: at Manager.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:48:09 volumio volumio[13046]: at Manager.ondecoded (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/manager.js:349:8) Jun 25 21:48:09 volumio volumio[13046]: at Decoder. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) Jun 25 21:48:09 volumio volumio[13046]: at Decoder.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:48:09 volumio volumio[13046]: at Decoder.add (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-parser/index.js:251:12) Jun 25 21:48:09 volumio volumio[13046]: at Manager.ondata (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/manager.js:339:16) Jun 25 21:48:09 volumio volumio[13046]: at Socket. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) { Jun 25 21:48:09 volumio volumio[13046]: code: 'ERR_INVALID_ARG_TYPE' Jun 25 21:48:09 volumio volumio[13046]: } Jun 25 21:48:09 volumio volumio[13046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 21:48:09 volumio sudo[13538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-25 21:47 Jun 25 21:48:09 volumio sudo[13538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:09 volumio sudo[13538]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:09 volumio vtcs[13404]: [2025-06-25 21:48:09.870] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 25 21:48:09 volumio vtcs[13404]: [2025-06-25 21:48:09.870] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 25 21:48:09 volumio volumio-remote-updater[681]: [2025-06-25 21:48:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 25 21:48:09 volumio volumio-remote-updater[681]: [2025-06-25 21:48:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 25 21:48:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 25 21:48:09 volumio qobuz-connect[13371]: 20250625 21:48:09.872 [13371.13371] INFO SampleApp: Socket closed Jun 25 21:48:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 25 21:48:09 volumio systemd[1]: Started dynamicswap service. Jun 25 21:48:09 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 25 21:48:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 25 21:48:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jun 25 21:48:10 volumio systemd[1]: Started dynamicswap service. Jun 25 21:48:10 volumio systemd[1]: Stopped Volumio Backend Module. Jun 25 21:48:10 volumio systemd[1]: Started Volumio Backend Module. Jun 25 21:48:10 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 25 21:48:10 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:10 volumio volumio[13549]: info: ----- Volumio3 ---- Jun 25 21:48:10 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:10 volumio volumio[13549]: info: ----- System startup ---- Jun 25 21:48:10 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:10 volumio volumio[13549]: info: MYVOLUMIO Environment detected Jun 25 21:48:10 volumio volumio[13549]: info: Plugin folders cleanup Jun 25 21:48:10 volumio volumio[13549]: info: Scanning into folder /volumio/app/plugins/ Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category audio_interface Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category miscellanea Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category music_service Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category plugins.json Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category system_controller Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category user_interface Jun 25 21:48:10 volumio volumio[13549]: info: Scanning into folder /data/plugins/ Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category system_controller Jun 25 21:48:10 volumio volumio[13549]: info: Scanning category user_interface Jun 25 21:48:10 volumio volumio[13549]: info: Plugin folders cleanup completed Jun 25 21:48:10 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:10 volumio volumio[13549]: info: ----- Core plugins startup ---- Jun 25 21:48:10 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:10 volumio volumio[13549]: info: Loading plugins from folder /volumio/app/plugins/ Jun 25 21:48:10 volumio volumio[13549]: info: Adding plugin upnp to MyMusic Plugins Jun 25 21:48:10 volumio volumio[13549]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 25 21:48:10 volumio volumio[13549]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 25 21:48:10 volumio volumio[13549]: info: Loading plugins from folder /data/plugins/ Jun 25 21:48:10 volumio volumio[13549]: info: Loading plugin "system"... Jun 25 21:48:10 volumio volumio[13549]: info: Loading plugin "appearance"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "network"... Jun 25 21:48:11 volumio volumio[13549]: info: Refreshing Cached IP Addresses Jun 25 21:48:11 volumio sudo[13575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 21:48:11 volumio sudo[13575]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:11 volumio sudo[13575]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:11 volumio sudo[13577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "services"... Jun 25 21:48:11 volumio sudo[13577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "alsa_controller"... Jun 25 21:48:11 volumio sudo[13577]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "wizard"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "networkfs"... Jun 25 21:48:11 volumio volumio[13549]: info: Cannot mount NAS Truenas at system boot, trial number 1 ,retrying in 5 seconds Jun 25 21:48:11 volumio volumio[13549]: info: Starting Udev Watcher for removable devices Jun 25 21:48:11 volumio volumio[13549]: info: Ignoring mount for partition: boot Jun 25 21:48:11 volumio volumio[13549]: info: Ignoring mount for partition: volumio Jun 25 21:48:11 volumio volumio[13549]: info: Ignoring mount for partition: volumio_data Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "volumio_command_line_client"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "upnp"... Jun 25 21:48:11 volumio volumio[13549]: info: [1750862891072] Starting Upmpd Daemon Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "my_music"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "mpd"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "upnp_browser"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "alarm-clock"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "airplay_emulation"... Jun 25 21:48:11 volumio volumio[13549]: info: Starting Shairport Sync Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "last_100"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "webradio"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "i2s_dacs"... Jun 25 21:48:11 volumio volumio[13549]: info: I2S DAC not set, start Auto-detection Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "volumiodiscovery"... Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** For more information see Jun 25 21:48:11 volumio node[13549]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 25 21:48:11 volumio volumio[13549]: *** WARNING *** For more information see Jun 25 21:48:11 volumio node[13549]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 25 21:48:11 volumio node[13549]: *** WARNING *** For more information see Jun 25 21:48:11 volumio node[13549]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 25 21:48:11 volumio node[13549]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 25 21:48:11 volumio node[13549]: *** WARNING *** For more information see Jun 25 21:48:11 volumio volumio[13549]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 25 21:48:11 volumio volumio[13549]: info: Discovery: Started advertising with name: Volumio Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "outputs"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "albumart"... Jun 25 21:48:11 volumio volumio[13549]: info: Plugin example_plugin is not enabled Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "inputs"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "updater_comm"... Jun 25 21:48:11 volumio volumio[13549]: Forking 3 albumart workers Jun 25 21:48:11 volumio volumio[13549]: info: Plugin mpdemulation is not enabled Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "rest_api"... Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "websocket"... Jun 25 21:48:11 volumio volumio[13549]: info: Starting Socket.io Server version 2.3.0 Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "serialampcontroller"... Jun 25 21:48:11 volumio volumio[13549]: info: Applying required configuration parameters for plugin serialampcontroller Jun 25 21:48:11 volumio volumio[13549]: info: Applying required configuration parameters for plugin serialampcontroller Jun 25 21:48:11 volumio volumio[13549]: info: Loading plugin "Systeminfo"... Jun 25 21:48:11 volumio volumio[13549]: Starting albumart workers Jun 25 21:48:11 volumio volumio[13549]: Starting albumart workers Jun 25 21:48:11 volumio volumio[13549]: Starting albumart workers Jun 25 21:48:11 volumio volumio[13549]: info: Loading i18n strings for locale en Jun 25 21:48:11 volumio volumio[13549]: Updating browse sources language Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::initPlayerControls Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: Express server listening on port 3000 Jun 25 21:48:11 volumio volumio[13549]: [Metrics] WebUI: 1s 531.96ms Jun 25 21:48:11 volumio volumio[13549]: info: Setting Device type: x86 Jun 25 21:48:11 volumio volumio[13549]: info: CoreStateMachine::resetVolumioState Jun 25 21:48:11 volumio volumio[13549]: info: CoreStateMachine::getcurrentVolume Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::volumioRetrievevolume Jun 25 21:48:11 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:11 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:11 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:11 volumio volumio[13549]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 25 21:48:11 volumio volumio[13549]: info: Completed loading Core Plugins Jun 25 21:48:11 volumio volumio[13549]: info: Preparing to generate the ALSA configuration file Jun 25 21:48:11 volumio volumio[13549]: info: Volumio Network Manager: Network status updated: 1 Jun 25 21:48:11 volumio volumio[13549]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 25 21:48:11 volumio volumio[13549]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 25 21:48:12 volumio volumio[13549]: info: Reloading queue from file Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::setRepeat null single undefined Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::setRandom null Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetQueue Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::getQueue Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getQueue Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jun 25 21:48:12 volumio volumio[13549]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 25 21:48:12 volumio volumio[13549]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 25 21:48:12 volumio volumio[13549]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetQueue Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::getQueue Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getQueue Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jun 25 21:48:12 volumio volumio[13549]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 25 21:48:12 volumio volumio[13549]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 25 21:48:12 volumio volumio[13549]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 25 21:48:12 volumio volumio[13549]: info: Asound.conf file written Jun 25 21:48:12 volumio sudo[13656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 25 21:48:12 volumio sudo[13656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio sudo[13656]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio volumio[13549]: info: Output device has changed, restarting MPD Jun 25 21:48:12 volumio sudo[13661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 25 21:48:12 volumio sudo[13661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio volumio[13549]: info: Output device has changed, restarting Shairport Sync Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio sudo[13661]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio sudo[13664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 25 21:48:12 volumio sudo[13664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio systemd[1]: Stopping Music Player Daemon... Jun 25 21:48:12 volumio volumio[13549]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 21:48:12 volumio volumio[13549]: info: ___________ START PLUGINS ___________ Jun 25 21:48:12 volumio volumio[13549]: info: ControllerMpd::onStart: Initializing MPD Jun 25 21:48:12 volumio volumio[13549]: info: Creating MPD Configuration file Jun 25 21:48:12 volumio sudo[13670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 25 21:48:12 volumio sudo[13670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 21:48:12 volumio systemd[1]: mpd.service: Succeeded. Jun 25 21:48:12 volumio sudo[13670]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio volumio[13549]: info: [1750862892157] CoreMusicLibrary::Adding element Media Servers Jun 25 21:48:12 volumio systemd[1]: Stopped Music Player Daemon. Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:12 volumio sudo[13673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 25 21:48:12 volumio sudo[13673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 21:48:12 volumio systemd[1]: Starting Music Player Daemon... Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 21:48:12 volumio volumio[13549]: info: [1750862892201] CoreMusicLibrary::Adding element Last_100 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 25 21:48:12 volumio volumio[13549]: info: [1750862892203] CoreMusicLibrary::Adding element Webradio Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 21:48:12 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 25 21:48:12 volumio systemd[1]: mpd.service: Succeeded. Jun 25 21:48:12 volumio systemd[1]: Stopped Music Player Daemon. Jun 25 21:48:12 volumio volumio[13549]: info: Initializing BBC Radios Jun 25 21:48:12 volumio systemd[1]: Starting Music Player Daemon... Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio sudo[13682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 25 21:48:12 volumio sudo[13682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio sudo[13682]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio volumio[13549]: info: Volumio Calling Home Jun 25 21:48:12 volumio volumio[13549]: info: MPD Permissions set Jun 25 21:48:12 volumio volumio[13549]: info: MPD Permissions set Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:12 volumio volumio[13549]: info: Discovery: adding 26b1d6ae-dd56-4b20-b469-44b44e03d27e Jun 25 21:48:12 volumio volumio[13549]: info: Discovery: Found device Volumio Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: Starting Shairport Sync Jun 25 21:48:12 volumio volumio[13549]: info: Starting Shairport Sync Jun 25 21:48:12 volumio sudo[13714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 21:48:12 volumio sudo[13714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio volumio[13549]: info: Starting Shairport Sync Jun 25 21:48:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 25 21:48:12 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 25 21:48:12 volumio sudo[13717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 21:48:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: (node:13549) UnhandledPromiseRejectionWarning: ReferenceError: match is not defined Jun 25 21:48:12 volumio volumio[13549]: at /data/plugins/system_controller/serialampcontroller/index.js:350:39 Jun 25 21:48:12 volumio volumio[13549]: at Array.forEach () Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser. (/data/plugins/system_controller/serialampcontroller/index.js:349:56) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.emit (events.js:400:28) Jun 25 21:48:12 volumio volumio[13549]: at addChunk (internal/streams/readable.js:293:12) Jun 25 21:48:12 volumio volumio[13549]: at readableAddChunk (internal/streams/readable.js:263:11) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.Readable.push (internal/streams/readable.js:206:10) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.Transform.push (internal/streams/transform.js:166:32) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser._transform (/data/plugins/system_controller/serialampcontroller/node_modules/@serialport/parser-delimiter/lib/index.js:35:12) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.Transform._read (internal/streams/transform.js:205:10) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.Transform._write (internal/streams/transform.js:193:12) Jun 25 21:48:12 volumio volumio[13549]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 25 21:48:12 volumio volumio[13549]: at ReadLineParser.Writable.write (internal/streams/writable.js:303:10) Jun 25 21:48:12 volumio volumio[13549]: at SerialPort.ondata (internal/streams/readable.js:731:22) Jun 25 21:48:12 volumio volumio[13549]: at SerialPort.emit (events.js:400:28) Jun 25 21:48:12 volumio volumio[13549]: at addChunk (internal/streams/readable.js:293:12) Jun 25 21:48:12 volumio volumio[13549]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 25 21:48:12 volumio volumio[13549]: (node:13549) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Jun 25 21:48:12 volumio sudo[13717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio volumio[13549]: (node:13549) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jun 25 21:48:12 volumio sudo[13721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 21:48:12 volumio sudo[13721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:12 volumio sudo[13714]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 25 21:48:12 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 25 21:48:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:12 volumio volumio[13549]: info: Shairport-Sync Started Jun 25 21:48:12 volumio volumio[13549]: Error adding Membership: Error: addMembership EINVAL Jun 25 21:48:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:12 volumio sudo[13721]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio sudo[13717]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio volumio[13549]: info: Shairport-Sync Started Jun 25 21:48:12 volumio volumio[13549]: info: Shairport-Sync Started Jun 25 21:48:12 volumio volumio[13549]: info: Volumio called home Jun 25 21:48:12 volumio mpd[13694]: Jun 25 21:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 25 21:48:12 volumio systemd[1]: Started Music Player Daemon. Jun 25 21:48:12 volumio sudo[13664]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio sudo[13673]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:12 volumio volumio[13549]: info: Completed starting Core Plugins Jun 25 21:48:12 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:12 volumio volumio[13549]: info: ----- MyVolumio plugins startup ---- Jun 25 21:48:12 volumio volumio[13549]: info: ------------------------------------------- Jun 25 21:48:12 volumio volumio[13549]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 25 21:48:12 volumio volumio[13549]: error: MPD error: The expression evaluated to a falsy value: Jun 25 21:48:12 volumio volumio[13549]: assert.ok(self.idling) Jun 25 21:48:12 volumio volumio[13549]: error: The expression evaluated to a falsy value: Jun 25 21:48:12 volumio volumio[13549]: assert.ok(self.idling) Jun 25 21:48:12 volumio volumio[13549]: info: MPD running with PID13694 Jun 25 21:48:12 volumio volumio[13549]: ,establishing connection Jun 25 21:48:12 volumio volumio[13549]: error: updateQueue error: null Jun 25 21:48:12 volumio volumio[13549]: error: updateQueue error: null Jun 25 21:48:12 volumio volumio[13549]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 21:48:12 volumio volumio[13549]: info: Received Get System Info Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 21:48:12 volumio volumio[13549]: info: Discovery: Getting this device information Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:12 volumio volumio[13549]: info: Listing playlists Jun 25 21:48:12 volumio volumio[13549]: info: CoreCommandRouter::volumioGetQueue Jun 25 21:48:12 volumio volumio[13549]: info: CoreStateMachine::getQueue Jun 25 21:48:12 volumio volumio[13549]: info: CorePlayQueue::getQueue Jun 25 21:48:13 volumio volumio[13549]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::volumioGetVisibleSources Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:13 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:13 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 21:48:13 volumio volumio[13549]: info: Received Get System Info Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 21:48:13 volumio volumio[13549]: info: Discovery: Getting this device information Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:13 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 21:48:13 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:13 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:13 volumio volumio[13549]: info: Listing playlists Jun 25 21:48:14 volumio volumio-remote-updater[681]: [2025-06-25 21:48:14] [connect] Successful connection Jun 25 21:48:14 volumio volumio-remote-updater[681]: [2025-06-25 21:48:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750862894 101 Jun 25 21:48:14 volumio volumio[13549]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 25 21:48:15 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 25 21:48:16 volumio volumio[13549]: info: Cannot mount NAS Truenas at system boot, trial number 2 ,retrying in 5 seconds Jun 25 21:48:17 volumio volumio[13549]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 25 21:48:18 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:18 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:21 volumio volumio[13549]: info: Cannot mount NAS Truenas at system boot, trial number 3 ,retrying in 5 seconds Jun 25 21:48:21 volumio sudo[13755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 21:48:21 volumio sudo[13755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:21 volumio sudo[13755]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:21 volumio sudo[13758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 21:48:21 volumio sudo[13758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:21 volumio sudo[13758]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:21 volumio sudo[13761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 25 21:48:21 volumio sudo[13761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:21 volumio sudo[13761]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:21 volumio volumio[13549]: info: Upmpdcli Daemon Started Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin bluetooth to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin multiroom to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin metavolumio to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin cd_controller to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 25 21:48:21 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:21 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:21 volumio volumio[13549]: info: Starting MyVolumio Remote Streaming Endpoints Jun 25 21:48:21 volumio volumio[13549]: info: MyVolumio login type: Token Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 25 21:48:21 volumio volumio[13549]: info: Starting Streaming Service Transparent Proxy Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 25 21:48:21 volumio volumio[13549]: info: Streaming services startup Jun 25 21:48:21 volumio volumio[13549]: info: Starting Streaming Daemon Jun 25 21:48:21 volumio sudo[13770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 25 21:48:21 volumio sudo[13770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:21 volumio sudo[13770]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:21 volumio volumio[13549]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 25 21:48:21 volumio volumio[13549]: error: Cannot start Volumio Streaming Daemon Jun 25 21:48:21 volumio volumio[13549]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 25 21:48:21 volumio volumio[13549]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 25 21:48:22 volumio volumio[13549]: STREAMING PROXY: Starting server on port 3245 Jun 25 21:48:22 volumio volumio[13549]: Node JS runtime: 14 Jun 25 21:48:22 volumio volumio[13549]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 25 21:48:23 volumio volumio[13549]: info: MyVolumio token set successfully Jun 25 21:48:23 volumio volumio[13549]: info: MYVOLUMIO: Adding device Jun 25 21:48:23 volumio volumio[13549]: info: MYVOLUMIO: Evaluating Server Jun 25 21:48:23 volumio volumio[13549]: info: MyVolumio Plan changed: premium Jun 25 21:48:23 volumio volumio[13549]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 25 21:48:23 volumio volumio[13549]: info: Removing browser output: myVolumio user plan is not superstar Jun 25 21:48:23 volumio volumio[13549]: info: Removing audio output: Jun 25 21:48:23 volumio volumio[13549]: info: MYVOLUMIO: Adding device Jun 25 21:48:23 volumio volumio[13549]: info: MYVOLUMIO: Evaluating Server Jun 25 21:48:23 volumio volumio[13549]: info: Remote config written successfully Jun 25 21:48:23 volumio volumio[13549]: info: Starting Tunnel 1 Jun 25 21:48:23 volumio volumio[13549]: info: Starting Tunnel Connection Checker Jun 25 21:48:23 volumio volumio[13549]: info: Completed starting MyVolumio Plugin Jun 25 21:48:23 volumio volumio[13549]: info: MYVolumio Device enabled Jun 25 21:48:23 volumio volumio[13549]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 25 21:48:23 volumio volumio[13549]: info: MyVolumio status changed Jun 25 21:48:23 volumio volumio[13549]: info: Streaming services startup Jun 25 21:48:23 volumio volumio[13549]: info: Starting Streaming Daemon Jun 25 21:48:23 volumio sudo[13810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 25 21:48:23 volumio sudo[13810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:23 volumio volumio[13549]: info: Setting Geolocation for MyVolumio to as1 Jun 25 21:48:23 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:23 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:23 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:23 volumio sudo[13810]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: error: Cannot start Volumio Streaming Daemon Jun 25 21:48:24 volumio volumio[13549]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 25 21:48:24 volumio volumio[13549]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 25 21:48:24 volumio volumio[13549]: info: [1750862904044] Starting BluetoothController Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 25 21:48:24 volumio sudo[13813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 25 21:48:24 volumio sudo[13813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13813]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 25 21:48:24 volumio volumio[13549]: info: MRS: MultiRoom plugin initialized Jun 25 21:48:24 volumio volumio[13549]: info: MRS: STOPPING SNAPCLIENT Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Snap server stop Jun 25 21:48:24 volumio sudo[13829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 25 21:48:24 volumio sudo[13829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio volumio[13549]: info: MRS: STOPPING volumioStreaming Jun 25 21:48:24 volumio sudo[13829]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio sudo[13832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 25 21:48:24 volumio sudo[13832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 25 21:48:24 volumio sudo[13835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13832]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 25 21:48:24 volumio sudo[13838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 25 21:48:24 volumio sudo[13838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13835]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio sudo[13838]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 25 21:48:24 volumio volumio[13549]: info: Preparing to generate the ALSA configuration file Jun 25 21:48:24 volumio volumio[13549]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 25 21:48:24 volumio volumio[13549]: info: Reading ALSA contributions from plugins. Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 21:48:24 volumio volumio[13549]: info: Setting Geolocation for MyVolumio to as1 Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Removed streaming files Jun 25 21:48:24 volumio volumio[13549]: info: MRS: volumioStreaming STOPPED Jun 25 21:48:24 volumio volumio[13549]: info: MRS: SNAPSERVER STOPPED Jun 25 21:48:24 volumio volumio[13549]: info: MRS: SNAPCLIENT STOPPED Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 25 21:48:24 volumio volumio[13549]: info: Asound.conf file written Jun 25 21:48:24 volumio sudo[13845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 25 21:48:24 volumio sudo[13845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13845]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: Output device has changed, restarting MPD Jun 25 21:48:24 volumio sudo[13850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 25 21:48:24 volumio sudo[13850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13850]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: Output device has changed, restarting Shairport Sync Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio sudo[13853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 25 21:48:24 volumio sudo[13853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio systemd[1]: Stopping Music Player Daemon... Jun 25 21:48:24 volumio systemd[1]: mpd.service: Succeeded. Jun 25 21:48:24 volumio systemd[1]: Stopped Music Player Daemon. Jun 25 21:48:24 volumio volumio[13549]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 25 21:48:24 volumio systemd[1]: Starting Music Player Daemon... Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Pushing multiroomSync output for this device Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:24 volumio volumio[13549]: info: Adding audio output: Jun 25 21:48:24 volumio sudo[13862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Jun 25 21:48:24 volumio volumio[13549]: info: Adding audio output: Jun 25 21:48:24 volumio sudo[13862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio sudo[13862]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 25 21:48:24 volumio volumio[13549]: info: Adding METAVOLUMIO REST API Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 25 21:48:24 volumio volumio[13549]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 25 21:48:24 volumio volumio[13549]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 25 21:48:24 volumio volumio[13549]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 25 21:48:24 volumio volumio[13549]: info: Preparing CD Folders Jun 25 21:48:24 volumio volumio[13549]: info: Adding CD REST API Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 25 21:48:24 volumio volumio[13549]: info: Starting UDEV Watcher for CD Jun 25 21:48:24 volumio volumio[13549]: info: Detecting CD presence with UDEV Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 25 21:48:24 volumio sudo[13858]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 25 21:48:24 volumio sudo[13858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 25 21:48:24 volumio sudo[13858]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: Adding inputs REST Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 25 21:48:24 volumio volumio[13549]: info: Scanning Audio Inputs Jun 25 21:48:24 volumio volumio[13549]: info: Checking against Known Cards name Jun 25 21:48:24 volumio volumio[13549]: info: Checking against Known Cards name Jun 25 21:48:24 volumio volumio[13549]: info: Adding Server instance for streaming Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 25 21:48:24 volumio volumio[13549]: error: Hi Res Audio Failed Login: Missing Login Data Jun 25 21:48:24 volumio volumio[13549]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 25 21:48:24 volumio volumio[13549]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jun 25 21:48:24 volumio volumio[13549]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 25 21:48:24 volumio sudo[13872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 25 21:48:24 volumio sudo[13872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 25 21:48:24 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jun 25 21:48:24 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 25 21:48:24 volumio systemd[1]: vtcs.service: Succeeded. Jun 25 21:48:24 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jun 25 21:48:24 volumio sudo[13872]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio sudo[13879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 25 21:48:24 volumio sudo[13879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio volumio[13549]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 25 21:48:24 volumio volumio[13549]: info: Stopping AccessToken refresher cron Jun 25 21:48:24 volumio sudo[13879]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: AccessToken refresher cron started Jun 25 21:48:24 volumio volumio[13549]: info: Stopping AccessToken refresher cron for QOBUZ Jun 25 21:48:24 volumio sudo[13881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 25 21:48:24 volumio sudo[13881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio volumio[13549]: info: AccessToken refresher cron started for QOBUZ Jun 25 21:48:24 volumio volumio[13549]: info: Adding TIDAL REST API Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 25 21:48:24 volumio volumio[13549]: info: Adding QOBUZ REST API Endpoints Jun 25 21:48:24 volumio volumio[13549]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 25 21:48:24 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 25 21:48:24 volumio qobuz-connect[13371]: 20250625 21:48:24.798 [13371.13371] INFO SampleApp: Stopping Local configuration server Jun 25 21:48:24 volumio systemd[1]: qobuz-connect.service: Succeeded. Jun 25 21:48:24 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 25 21:48:24 volumio volumio[13549]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' Jun 25 21:48:24 volumio volumio[13549]: Can't get device info: No such device Jun 25 21:48:24 volumio volumio[13549]: info: MPD Permissions set Jun 25 21:48:24 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jun 25 21:48:24 volumio sudo[13881]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: Executing endpoint qc_getconfig Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 25 21:48:24 volumio volumio[13549]: info: Starting Shairport Sync Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.915 [13883.13883] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.917 [13883.13883] INFO VolumeManager: [0x5577fe8a0d10]: Setting new playback volume: 75 Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.917 [13883.13883] INFO VolumeManager: [0x5577fe8a0d10]: Setting new mute state: 0 Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.917 [13883.13883] INFO QobuzConnect: [0x5577fe8a1f30]: Client initialized! Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.917 [13883.13883] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.921 [13883.13883] INFO LocalConfigManager: [0x5577fe8a0000]: Starting Local Configuration server Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.921 [13883.13883] INFO SampleApp: Starting Local configuration server Jun 25 21:48:24 volumio qobuz-connect[13883]: 20250625 21:48:24.922 [13883.13883] INFO SampleApp: Connected to UNIX socket client 0x5577fe8881c0 Jun 25 21:48:24 volumio volumio[13549]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 25 21:48:24 volumio volumio[13549]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 25 21:48:24 volumio sudo[13894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 25 21:48:24 volumio sudo[13894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 25 21:48:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 25 21:48:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:24 volumio volumio[13549]: info: MRS: Found cast device: SKWAMX3-9ac78e3ee9d2b229d1842f6f02e771d8 Jun 25 21:48:24 volumio volumio[13549]: info: Adding audio output: Jun 25 21:48:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 25 21:48:24 volumio sudo[13894]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:24 volumio volumio[13549]: info: Shairport-Sync Started Jun 25 21:48:24 volumio volumio[13549]: info: Updating MyVolumio device info Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:25 volumio mpd[13868]: Jun 25 21:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 25 21:48:25 volumio systemd[1]: Started Music Player Daemon. Jun 25 21:48:25 volumio sudo[13853]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:25 volumio qobuz-connect[13883]: 20250625 21:48:25.064 [13883.13883] INFO SampleApp: Playback volume changed: 75 Jun 25 21:48:25 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:25 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:25 volumio volumio[13549]: error: updateQueue error: null Jun 25 21:48:25 volumio volumio[13549]: info: Successfully Added MyVolumio device Jun 25 21:48:25 volumio volumio[13549]: info: Successfully Added MyVolumio device Jun 25 21:48:25 volumio volumio[13549]: info: Updating MyVolumio device info Jun 25 21:48:25 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:25 volumio volumio[13549]: info: Successfully Updated MyVolumio device Jun 25 21:48:26 volumio volumio[13549]: info: Successfully Updated MyVolumio device Jun 25 21:48:26 volumio volumio[13549]: info: Cannot mount NAS Truenas at system boot, trial number 4 ,retrying in 5 seconds Jun 25 21:48:27 volumio sudo[13902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 25 21:48:27 volumio sudo[13902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:27 volumio sudo[13902]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:27 volumio volumio[13549]: info: Volumio BT Module successfully started Jun 25 21:48:27 volumio volumio[13549]: info: TidalConnect service stoped! Jun 25 21:48:27 volumio volumio[13549]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 25 21:48:27 volumio volumio[13549]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 25 21:48:27 volumio sudo[13913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 25 21:48:27 volumio sudo[13913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:27 volumio systemd[1]: Started Volumio Tidal Connect Service. Jun 25 21:48:27 volumio sudo[13913]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:27 volumio volumio[13549]: info: Executing endpoint tc_getconfig Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 25 21:48:27 volumio vtcs[13915]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 25 21:48:27 volumio vtcs[13915]: STARTED TidalConnect services. Jun 25 21:48:27 volumio volumio[13549]: info: Executing endpoint tc_connect Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 25 21:48:27 volumio volumio[13549]: info: Connecting to TidalConnect Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::servicePushState Jun 25 21:48:27 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:27 volumio volumio[13549]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:27 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::servicePushState Jun 25 21:48:27 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:27 volumio volumio[13549]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:27 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:27 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:27 volumio volumio[13549]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 25 21:48:28 volumio sudo[13939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 25 21:48:28 volumio sudo[13939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 21:48:28 volumio autossh[13431]: received signal to exit (15) Jun 25 21:48:28 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Jun 25 21:48:28 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jun 25 21:48:28 volumio systemd[1]: sshtunnel.service: Succeeded. Jun 25 21:48:28 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 25 21:48:28 volumio volumio[13549]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 25 21:48:28 volumio volumio[13549]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 25 21:48:28 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 25 21:48:28 volumio sudo[13939]: pam_unix(sudo:session): session closed for user root Jun 25 21:48:28 volumio autossh[13942]: port set to 0, monitoring disabled Jun 25 21:48:28 volumio autossh[13942]: starting ssh (count 1) Jun 25 21:48:28 volumio volumio[13549]: info: Remote SSH Started Jun 25 21:48:28 volumio autossh[13942]: ssh child pid is 13945 Jun 25 21:48:28 volumio volumio[13549]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 25 21:48:28 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:28 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:28 volumio volumiossh-tunnel[13941]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Jun 25 21:48:29 volumio volumio[13549]: info: MRS: Getting audio outputs on start Jun 25 21:48:29 volumio volumio[13549]: info: MRS: Requesting all other devices output Jun 25 21:48:30 volumio volumio[13549]: info: TidalConnect service started! Jun 25 21:48:30 volumio volumio[13549]: [Metrics] CommandRouter: 20s 309.48ms Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::volumiosetStartupVolume Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:30 volumio volumio[13549]: info: VolumeController:: Setting startup Volume 100 Jun 25 21:48:30 volumio volumio[13549]: info: VolumeController::SetAlsaVolume100 Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::Close All Modals sent Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::Close All Modals sent Jun 25 21:48:30 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:30 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:30 volumio volumio[13549]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:30 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:30 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:30 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:31 volumio volumio[13549]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 25 21:48:31 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 25 21:48:31 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 21:48:31 volumio volumio[13549]: No protocol specified Jun 25 21:48:31 volumio volumio[13549]: xcb_connection_has_error() returned true Jun 25 21:48:31 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 25 21:48:32 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:32 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:33 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 21:48:33 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 25 21:48:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:36 volumio volumio[13549]: info: Disabling plugin serialampcontroller Jun 25 21:48:36 volumio volumio[13549]: info: Done. Jun 25 21:48:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 21:48:36 volumio volumio[13549]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 25 21:48:36 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:36 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:37 volumio volumio[13549]: info: BOOT COMPLETED Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:41 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::volumioGetBrowseSources Jun 25 21:48:41 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 21:48:42 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 21:48:42 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:48:42 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 25 21:48:42 volumio volumio-remote-updater[681]: No test mode Jun 25 21:48:42 volumio volumio-remote-updater[681]: No alpha test mode Jun 25 21:48:42 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 25 21:48:42 volumio volumio[13549]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 25 21:48:42 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 25 21:48:43 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:43 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:43 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:43 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:43 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:43 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:44 volumio volumio[13549]: info: Preload queue cleared Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::ClearQueue Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::stop Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::clearPlayQueue Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::saveQueue Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioPushQueue Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::addQueueItems Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::addQueueItems Jun 25 21:48:44 volumio volumio[13549]: info: Preload queue cleared Jun 25 21:48:44 volumio volumio[13549]: info: Adding Item to queue: albums://%E3%82%A8%E3%83%B4%E3%82%A1%E3%83%B3%E3%82%B2%E3%83%AA%E3%82%AA%E3%83%B3%E3%83%BB%E3%82%B5%E3%82%A6%E3%83%B3%E3%83%89%E3%83%88%E3%83%A9%E3%83%83%E3%82%AF/NEON%20GENESIS%20EVANGELION%E3%80%902013%20HR%20Remaster%20Ver.%E3%80%91 Jun 25 21:48:44 volumio volumio[13549]: info: Exploding uri albums://%E3%82%A8%E3%83%B4%E3%82%A1%E3%83%B3%E3%82%B2%E3%83%AA%E3%82%AA%E3%83%B3%E3%83%BB%E3%82%B5%E3%82%A6%E3%83%B3%E3%83%89%E3%83%88%E3%83%A9%E3%83%83%E3%82%AF/NEON%20GENESIS%20EVANGELION%E3%80%902013%20HR%20Remaster%20Ver.%E3%80%91 in service mpd Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioPushQueue Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::saveQueue Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::updateTrackBlock Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrackBlock Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioPlay Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::play index 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::stop Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::play index undefined Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::startPlaybackTimer Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::clearAddPlayTracks NAS/Truenas/1.残酷な天使のテーゼ(Director's Edit. Version).flac Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand stop took 1 milliseconds Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 21:48:44 volumio volumio[13549]: info: Jun 25 21:48:44 volumio volumio[13549]: ---------------------------- MPD announces system playlist update Jun 25 21:48:44 volumio volumio[13549]: info: Ignoring MPD Status Update Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand clear took 1 milliseconds Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand add "NAS/Truenas/1.残酷な天使のテーゼ(Director's Edit. Version).flac" Jun 25 21:48:44 volumio volumio[13549]: error: updateQueue error: null Jun 25 21:48:44 volumio volumio[13549]: info: Jun 25 21:48:44 volumio volumio[13549]: ---------------------------- MPD announces system playlist update Jun 25 21:48:44 volumio volumio[13549]: info: Ignoring MPD Status Update Jun 25 21:48:44 volumio volumio[13549]: info: ------------------------------ 2ms Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand add "NAS/Truenas/1.残酷な天使のテーゼ(Director's Edit. Version).flac" took 1 milliseconds Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand play Jun 25 21:48:44 volumio volumio[13549]: info: ------------------------------ 2ms Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand play took 1 milliseconds Jun 25 21:48:44 volumio volumio[13549]: info: Jun 25 21:48:44 volumio volumio[13549]: ---------------------------- MPD announces state update: player Jun 25 21:48:44 volumio volumio[13549]: info: ControllerMpd::getState Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:44 volumio volumio[13549]: info: Jun 25 21:48:44 volumio volumio[13549]: ---------------------------- MPD announces state update: player Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand status took 50 milliseconds Jun 25 21:48:44 volumio volumio[13549]: info: ControllerMpd::getState Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand status Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::parseState Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand status took 2 milliseconds Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::parseState Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:44 volumio volumio[13549]: info: ControllerMpd::pushState Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::servicePushState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"残酷な天使のテーゼ(Director's Edit. Version)","artist":"高橋洋子","album":"NEON GENESIS EVANGELION【2013 HR Remaster Ver.】","uri":"NAS/Truenas/1.残酷な天使のテーゼ(Director's Edit. Version).flac","trackType":"flac"} Jun 25 21:48:44 volumio volumio[13549]: verbose: CURRENT POSITION 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::syncState stateService play Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::syncState currentStatus stop Jun 25 21:48:44 volumio volumio[13549]: info: ------------------------------ 61ms Jun 25 21:48:44 volumio volumio[13549]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 25 21:48:44 volumio volumio[13549]: verbose: ControllerMpd::parseTrackInfo Jun 25 21:48:44 volumio volumio[13549]: info: ControllerMpd::pushState Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::servicePushState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: verbose: STATE SERVICE {"status":"play","position":0,"seek":849,"duration":243,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9230 Kbps","isStreaming":false,"title":"残酷な天使のテーゼ(Director's Edit. Version)","artist":"高橋洋子","album":"NEON GENESIS EVANGELION【2013 HR Remaster Ver.】","uri":"NAS/Truenas/1.残酷な天使のテーゼ(Director's Edit. Version).flac","trackType":"flac"} Jun 25 21:48:44 volumio volumio[13549]: verbose: CURRENT POSITION 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::syncState stateService play Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::syncState currentStatus play Jun 25 21:48:44 volumio volumio[13549]: info: Received an update from plugin. extracting info from payload Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:44 volumio volumio[13549]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:44 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreStateMachine::pushState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioPushState Jun 25 21:48:44 volumio volumio[13549]: info: MRS: Pushing multiroomSync output update for this device Jun 25 21:48:44 volumio volumio[13549]: info: MRS: Pushing multiroomSync output Jun 25 21:48:44 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:44 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:44 volumio volumio[13549]: info: ------------------------------ 45ms Jun 25 21:48:46 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:46 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:46 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:46 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:46 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:46 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:47 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:48:47 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:48:49 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:49 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:49 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:49 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:48:49 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:48:49 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:06 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:06 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:08 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:08 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:08 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:08 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:08 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:08 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:19 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:19 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:20 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 21:49:20 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 25 21:49:21 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:21 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:21 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:21 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:21 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:21 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:22 volumio volumio[13549]: error: Failed request for metavolumio API Jun 25 21:49:22 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:22 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:24 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:24 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:24 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:24 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 21:49:27 volumio volumio[13549]: No protocol specified Jun 25 21:49:27 volumio volumio[13549]: xcb_connection_has_error() returned true Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 25 21:49:27 volumio volumio[13549]: No protocol specified Jun 25 21:49:27 volumio volumio[13549]: xcb_connection_has_error() returned true Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 25 21:49:27 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 25 21:49:32 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:32 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:34 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:34 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:34 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:34 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 21:49:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 25 21:49:36 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:36 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:36 volumio volumio[13549]: info: Executing endpoint metavolumio Jun 25 21:49:36 volumio volumio[13549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 25 21:49:39 volumio volumio[13549]: info: Enabling plugin serialampcontroller Jun 25 21:49:39 volumio volumio[13549]: info: Loading plugin "serialampcontroller"... Jun 25 21:49:39 volumio volumio[13549]: info: Applying required configuration parameters for plugin serialampcontroller Jun 25 21:49:39 volumio volumio[13549]: info: Applying required configuration parameters for plugin serialampcontroller Jun 25 21:49:39 volumio volumio[13549]: info: PLUGIN START: serialampcontroller Jun 25 21:49:39 volumio volumio[13549]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 25 21:49:39 volumio volumio[13549]: info: CoreCommandRouter::volumioGetState Jun 25 21:49:39 volumio volumio[13549]: info: CorePlayQueue::getTrack 0 Jun 25 21:49:39 volumio volumio[13549]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 21:49:39 volumio volumio[13549]: TypeError: Cannot read property 'indexOf' of undefined Jun 25 21:49:39 volumio volumio[13549]: at serialampcontroller.sendCommand (/data/plugins/system_controller/serialampcontroller/index.js:471:78) Jun 25 21:49:39 volumio volumio[13549]: at Socket. (/data/plugins/system_controller/serialampcontroller/index.js:60:22) Jun 25 21:49:39 volumio volumio[13549]: at Socket.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:49:39 volumio volumio[13549]: at Socket.onevent (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/socket.js:278:10) Jun 25 21:49:39 volumio volumio[13549]: at Socket.onpacket (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/socket.js:236:12) Jun 25 21:49:39 volumio volumio[13549]: at Manager. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) Jun 25 21:49:39 volumio volumio[13549]: at Manager.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:49:39 volumio volumio[13549]: at Manager.ondecoded (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/manager.js:349:8) Jun 25 21:49:39 volumio volumio[13549]: at Decoder. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) Jun 25 21:49:39 volumio volumio[13549]: at Decoder.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:49:39 volumio volumio[13549]: at Decoder.add (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-parser/index.js:251:12) Jun 25 21:49:39 volumio volumio[13549]: at Manager.ondata (/data/plugins/system_controller/serialampcontroller/node_modules/socket.io-client/lib/manager.js:339:16) Jun 25 21:49:39 volumio volumio[13549]: at Socket. (/data/plugins/system_controller/serialampcontroller/node_modules/component-bind/index.js:21:15) Jun 25 21:49:39 volumio volumio[13549]: at Socket.Emitter.emit (/data/plugins/system_controller/serialampcontroller/node_modules/component-emitter/index.js:145:20) Jun 25 21:49:39 volumio volumio[13549]: at Socket.onPacket (/data/plugins/system_controller/serialampcontroller/node_modules/engine.io-client/lib/socket.js:461:14) Jun 25 21:49:39 volumio volumio[13549]: at XHR. (/data/plugins/system_controller/serialampcontroller/node_modules/engine.io-client/lib/socket.js:278:10) Jun 25 21:49:39 volumio volumio[13549]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 21:49:39 volumio sudo[14136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-25 21:48 Jun 25 21:49:39 volumio sudo[14136]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"