-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-04-12 00:12:45 UTC. -- Apr 12 00:11:03 volumiomercuryv2 ntpd[652]: 109.74.206.120 local addr 192.168.0.10 -> Apr 12 00:11:04 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Apr 12 00:11:04 volumiomercuryv2 volumio[921]: info: [bandcamp-browse] browseUri: bandcamp Apr 12 00:11:07 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:07 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:13 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 00:11:13 volumiomercuryv2 volumio[921]: info: CURURI: artists:// Apr 12 00:11:13 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:13 volumiomercuryv2 ntpd[652]: 188.114.116.1 local addr 192.168.0.10 -> Apr 12 00:11:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 00:11:16 volumiomercuryv2 volumio[921]: info: CURURI: albums:// Apr 12 00:11:16 volumiomercuryv2 volumio[921]: info: listAlbums - loading Albums from cache Apr 12 00:11:16 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:21 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 00:11:21 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:28 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 00:11:28 volumiomercuryv2 volumio[921]: info: CURURI: playlists Apr 12 00:11:28 volumiomercuryv2 volumio[921]: info: Listing playlists Apr 12 00:11:28 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: [bandcamp-browse] browseUri: bandcamp Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:34 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:38 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Apr 12 00:11:38 volumiomercuryv2 volumio[921]: info: [bandcamp-browse] browseUri: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::ClearQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 1 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::stPlaybackTimer Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::updateTrackBlock Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrackBlock Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::serviceStop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 1 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::serviceStop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: [1712880700278] ControllerQobuz::stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::clearPlayQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::saveQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::addQueueItems Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::addQueueItems Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Adding Item to queue: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Exploding uri bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729 in service bandcamp Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: [bandcamp-browse] explodeUri: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::saveQueue Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::updateTrackBlock Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrackBlock Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPlay Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::play index 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::play index undefined Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::startPlaybackTimer Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces state update: player Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand stop took 256 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::getState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand status Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand status took 8 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseTrackInfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 19ms Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand stop took 2 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces system playlist update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Ignoring MPD Status Update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand clear took 1 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand addid "https://bandcamp.com/stream_redirect?enc=mp3-128&track_id=113787748&ts=1712880699&t=585ad9bce71dc633b97aab6be5603c6657649a9d&t.mp3" Apr 12 00:11:40 volumiomercuryv2 volumio[921]: error: updateQueue error: null Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces system playlist update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Ignoring MPD Status Update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 2ms Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand addid "https://bandcamp.com/stream_redirect?enc=mp3-128&track_id=113787748&ts=1712880699&t=585ad9bce71dc633b97aab6be5603c6657649a9d&t.mp3" took 2 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: MPD COMMAND [object Object] Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: MPD COMMAND [object Object] Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces system playlist update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Ignoring MPD Status Update Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 4ms Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand play Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 3ms Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand play took 2 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces state update: player Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::getState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand status Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:40 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces state update: player Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand status took 3 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::getState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand status Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand status took 4 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseTrackInfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5611,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Metal Show","artist":"3/29/2024","album":"Bandcamp Show","uri":"https://bandcamp.com/stream_redirect?enc=mp3-128&track_id=113787748&ts=1712880699&t=585ad9bce71dc633b97aab6be5603c6657649a9d&t.mp3","trackType":"mp3"} Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: CURRENT POSITION 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState stateService play Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState currentStatus stop Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 20ms Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseTrackInfo Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ControllerMpd::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5611,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Metal Show","artist":"3/29/2024","album":"Bandcamp Show","uri":"https://bandcamp.com/stream_redirect?enc=mp3-128&track_id=113787748&ts=1712880699&t=585ad9bce71dc633b97aab6be5603c6657649a9d&t.mp3","trackType":"mp3"} Apr 12 00:11:40 volumiomercuryv2 volumio[921]: verbose: CURRENT POSITION 0 Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState stateService play Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState currentStatus play Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: Received an update from plugin. extracting info from payload Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:11:40 volumiomercuryv2 volumio[921]: info: ------------------------------ 53ms Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fhamferd.bandcamp.com%2Ftrack%2Fhamfer Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fdodsrit.bandcamp.com%2Ftrack%2Fnocturnal-fire Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fvorgaband.bandcamp.com%2Ftrack%2Fterminal Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fapparitiondeath.bandcamp.com%2Ftrack%2Fimminent-expanse-of-silence-and-not-or-not Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fsentientruin.bandcamp.com%2Ftrack%2Fblighted Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrodequin.bandcamp.com%2Ftrack%2Fdiabolical-edict Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fego-death Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fhesitation-wound Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fsocial-grace-2 Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Frope-drag Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fsocial-grace-2 Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fsnifter Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fsocial-grace-2 Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fbrat504.bandcamp.com%2Ftrack%2Fsugar-bastard Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fsinisterfeeling.bandcamp.com%2Ftrack%2Fencased-in-cement Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fhatexforce.bandcamp.com%2Ftrack%2Fdescending-down Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Flockslip.bandcamp.com%2Ftrack%2Fguillotine-blueprint Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Fmutilationbarbecue-maggotstomp.bandcamp.com%2Ftrack%2Fauto-anthropophagy Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preloading song: bandcamp/show@inSection=1/show@showUrl=https%3A%2F%2Fbandcamp.com%2F%3Fshow%3D729/track@trackUrl=https%3A%2F%2Flisten.20buckspin.com%2Ftrack%2Fmaze-envy Apr 12 00:11:45 volumiomercuryv2 volumio[921]: info: Preload queue cleared Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::stPlaybackTimer Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::servicePause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: ControllerMpd::pause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand pause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: Apr 12 00:11:55 volumiomercuryv2 volumio[921]: ---------------------------- MPD announces state update: player Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: sendMpdCommand pause took 55 milliseconds Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: ControllerMpd::getState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand status Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: sendMpdCommand status took 0 milliseconds Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: ControllerMpd::parseTrackInfo Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: ControllerMpd::pushState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15611,"duration":5611,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Metal Show","artist":"3/29/2024","album":"Bandcamp Show","uri":"https://bandcamp.com/stream_redirect?enc=mp3-128&track_id=113787748&ts=1712880699&t=585ad9bce71dc633b97aab6be5603c6657649a9d&t.mp3","trackType":"mp3"} Apr 12 00:11:55 volumiomercuryv2 volumio[921]: verbose: CURRENT POSITION 0 Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState stateService pause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::syncState currentStatus pause Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: CoreStateMachine::stPlaybackTimer Apr 12 00:11:55 volumiomercuryv2 volumio[921]: info: ------------------------------ 10ms Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Retrieving Cloud Streaming UI Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Getting Tidal Cloud Configuration Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Getting Qobuz Cloud Configuration Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Getting Spotify Cloud Configuration Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Saving Spotify Acccount Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:01 volumiomercuryv2 volumio[921]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Got Tidal Cloud Configuration Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:01 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 00:12:05 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:10 volumiomercuryv2 ntpd[652]: 178.215.228.24 local addr 192.168.0.10 -> Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Retrieving Cloud Streaming UI Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Getting Tidal Cloud Configuration Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Getting Qobuz Cloud Configuration Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Getting Spotify Cloud Configuration Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Saving Spotify Acccount Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:16 volumiomercuryv2 volumio[921]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Got Tidal Cloud Configuration Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:16 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Retrieving Cloud Streaming UI Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Getting Tidal Cloud Configuration Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Getting Qobuz Cloud Configuration Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Getting Spotify Cloud Configuration Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Asking plugin for UI Config Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Saving Spotify Acccount Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:23 volumiomercuryv2 volumio[921]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Got Tidal Cloud Configuration Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: Got it Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:12:23 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 00:12:24 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:24 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: Disabling MyMusic plugin smart_inputs Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service smart_inputs Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: Removeing all smart Inputs Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesHiFiBerry ADC Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 00:12:25 volumiomercuryv2 volumio[921]: Cannot find translation for sourceMedia Servers Apr 12 00:12:25 volumiomercuryv2 volumio[921]: Cannot find translation for sourceBandcamp Discover Apr 12 00:12:25 volumiomercuryv2 volumio[921]: Cannot find translation for sourceQOBUZ Apr 12 00:12:25 volumiomercuryv2 volumio[921]: info: Disabling plugin smart_inputs Apr 12 00:12:28 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: Enabling MyMusic plugin smart_inputs Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: Enabling plugin smart_inputs Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: Adding inputs REST Endpoints Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: Updating scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 12 00:12:31 volumiomercuryv2 volumio[921]: info: Scanning Audio Inputs Apr 12 00:12:32 volumiomercuryv2 volumio[921]: xcb_connection_has_error() returned true Apr 12 00:12:32 volumiomercuryv2 volumio[921]: info: Checking against Known Cards name Apr 12 00:12:32 volumiomercuryv2 volumio[921]: info: Checking against Known Cards name Apr 12 00:12:32 volumiomercuryv2 volumio[921]: info: Adding Server instance for streaming Apr 12 00:12:32 volumiomercuryv2 volumio[921]: info: Done. Apr 12 00:12:32 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:36 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Disabling MyMusic plugin multiroom Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Disabling plugin multiroom Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Preparing to generate the ALSA configuration file Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Asound.conf file written Apr 12 00:12:37 volumiomercuryv2 sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 00:12:37 volumiomercuryv2 sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 sudo[1575]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 volumio[921]: xcb_connection_has_error() returned true Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Output device has changed, restarting MPD Apr 12 00:12:37 volumiomercuryv2 sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 00:12:37 volumiomercuryv2 sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 sudo[1580]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Output device has changed, restarting Shairport Sync Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 00:12:37 volumiomercuryv2 sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 00:12:37 volumiomercuryv2 sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopping Music Player Daemon... Apr 12 00:12:37 volumiomercuryv2 volumio[921]: xcb_connection_has_error() returned true Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 00:12:37 volumiomercuryv2 volumio[921]: xcb_connection_has_error() returned true Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 systemd[1]: mpd.service: Succeeded. Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopped Music Player Daemon. Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Starting Music Player Daemon... Apr 12 00:12:37 volumiomercuryv2 vtcs[1293]: [2024-04-12 00:12:37.252] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 12 00:12:37 volumiomercuryv2 vtcs[1293]: [2024-04-12 00:12:37.253] [tisoc] [error] [SpkconServer.cpp:368] recv error. socket disconnected Apr 12 00:12:37 volumiomercuryv2 vtcs[1293]: [2024-04-12 00:12:37.254] [tisoc] [error] [SpkconServer.cpp:461] socket write error. fd=3 errorno=32 error=Broken pipe Apr 12 00:12:37 volumiomercuryv2 sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 00:12:37 volumiomercuryv2 sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 12 00:12:37 volumiomercuryv2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 12 00:12:37 volumiomercuryv2 systemd[1]: vtcs.service: Succeeded. Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 12 00:12:37 volumiomercuryv2 sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 00:12:37 volumiomercuryv2 sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 sudo[1592]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: MPD Permissions set Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Starting Shairport Sync Apr 12 00:12:37 volumiomercuryv2 sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 00:12:37 volumiomercuryv2 sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 00:12:37 volumiomercuryv2 systemd[1]: shairport-sync.service: Succeeded. Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 00:12:37 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 00:12:37 volumiomercuryv2 sudo[1606]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 volumio[921]: info: Shairport-Sync Started Apr 12 00:12:37 volumiomercuryv2 mpd[1600]: Apr 12 00:12 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 00:12:37 volumiomercuryv2 mpd[1600]: Apr 12 00:12 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 12 00:12:37 volumiomercuryv2 mpd[1600]: Apr 12 00:12 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 12 00:12:37 volumiomercuryv2 systemd[1]: Started Music Player Daemon. Apr 12 00:12:37 volumiomercuryv2 sudo[1583]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:37 volumiomercuryv2 volumio[921]: error: updateQueue error: null Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: TidalConnect service stoped! Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: TidalConnect service stoped! Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 00:12:40 volumiomercuryv2 sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 00:12:40 volumiomercuryv2 sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:40 volumiomercuryv2 systemd[1]: Started Volumio Tidal Connect Service. Apr 12 00:12:40 volumiomercuryv2 sudo[1623]: pam_unix(sudo:session): session closed for user root Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Executing endpoint tc_getconfig Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 12 00:12:40 volumiomercuryv2 vtcs[1625]: STARTING TidalConnect services, version: 1.1.7.5 Apr 12 00:12:40 volumiomercuryv2 vtcs[1625]: STARTED TidalConnect services. Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Executing endpoint tc_connect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Connecting to TidalConnect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::servicePushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreStateMachine::pushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::volumioPushState Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output update for this device Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: MRS: Pushing multiroomSync output Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CorePlayQueue::getTrack 0 Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 12 00:12:40 volumiomercuryv2 volumio[921]: info: Disabling MyMusic plugin upnp Apr 12 00:12:40 volumiomercuryv2 sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 12 00:12:40 volumiomercuryv2 sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 00:12:40 volumiomercuryv2 systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 12 00:12:40 volumiomercuryv2 volumio[921]: error: Upnp client error: Error: This socket has been ended by the other party Apr 12 00:12:40 volumiomercuryv2 volumio[921]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 00:12:43 volumiomercuryv2 volumio[921]: info: TidalConnect service started! Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: Enabling MyMusic plugin upnp Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: Enabling plugin upnp Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: Loading plugin "upnp"... Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: [1712880764658] Starting Upmpd Daemon Apr 12 00:12:44 volumiomercuryv2 volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 00:12:44 volumiomercuryv2 volumio[921]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 00:12:44 volumiomercuryv2 volumio[921]: Error: listen EADDRINUSE: address already in use :::6599 Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at listenInCluster (net.js:1366:12) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at Server.listen (net.js:1452:7) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at Promise._successFn (/volumio/app/pluginmanager.js:1841:19) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 12 00:12:44 volumiomercuryv2 volumio[921]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 12 00:12:44 volumiomercuryv2 volumio[921]: code: 'EADDRINUSE', Apr 12 00:12:44 volumiomercuryv2 volumio[921]: errno: -98, Apr 12 00:12:44 volumiomercuryv2 volumio[921]: syscall: 'listen', Apr 12 00:12:44 volumiomercuryv2 volumio[921]: address: '::', Apr 12 00:12:44 volumiomercuryv2 volumio[921]: port: 6599 Apr 12 00:12:44 volumiomercuryv2 volumio[921]: } Apr 12 00:12:44 volumiomercuryv2 volumio[921]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 00:12:45 volumiomercuryv2 sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 00:11 Apr 12 00:12:45 volumiomercuryv2 sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"