-- Logs begin at Thu 2019-02-14 03:11:58 MST, end at Tue 2025-03-11 17:21:34 MST. -- Mar 11 17:20:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:01 volumio volumio[845]: info: CURURI: music-library/INTERNAL/FusionDsp/tools Mar 11 17:20:01 volumio volumio[845]: error: Failed LSINFO: null Mar 11 17:20:01 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:02 volumio volumio[845]: info: CorePlayQueue::getQueue Mar 11 17:20:02 volumio volumio[845]: info: [1741738802546] ControllerPandora::removeTrack Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::removeQueueItem Mar 11 17:20:02 volumio volumio[845]: info: CorePlayQueue::removeQueueItem 1 Mar 11 17:20:02 volumio volumio[845]: info: CorePlayQueue::saveQueue Mar 11 17:20:02 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Mar 11 17:20:02 volumio volumio[845]: info: [1741738802551] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=563209008792169478trackId=2145867107312348610 at queue index: 1 Mar 11 17:20:02 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:20:02 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:20:02 volumio volumio[845]: info: sendMpdCommand status took 12 milliseconds Mar 11 17:20:02 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:20:02 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 17:20:02 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 11 17:20:02 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Mar 11 17:20:02 volumio volumio[845]: info: [1741738802565] ControllerPandora::pushState Mar 11 17:20:02 volumio volumio[845]: info: CoreCommandRouter::servicePushState Mar 11 17:20:02 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Mar 11 17:20:02 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21611,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Would? (2022 Remaster)","artist":"Alice In Chains","album":"Dirt (2022 Remaster)","uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/7423283320774896785.mp3?version=5&lid=123431942&token=kqR6YUq4iaZehRrCf5d4XW%2Bq91synuIoCB0GXjH182qjb0ZJAxqNICTV2ja3Yvrw42SWwOJ9FpCOCSHdV%2Bp146egvg5XM4eUp2G6uz%2Fx%2F4TODArB2U6AhPio2iriiQClThigLtVYWCOHpbW8UJQ62bQ4MGAwE92F0%2F7S2WcNejFqnLNJMXsFOIMmr7zx0WnTwVrvaBXa%2BO%2BlyWzR8h4h11%2FI8468BT9hxBFAgQOC3RagWq8nmKkQUPJ3EWJiCnVVu%2Fz5TgPXr1XeEV1vkttpflbnOA%2Falzclb6n7ZBwHwtLvZMs7%2ByIRjllr6aMBjqiydahW34o6Cig%3D","trackType":"mp3"} Mar 11 17:20:02 volumio volumio[845]: verbose: CURRENT POSITION 1 Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Mar 11 17:20:02 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::pushState Mar 11 17:20:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 17:20:02 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::pushState Mar 11 17:20:02 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Mar 11 17:20:02 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 11 17:20:02 volumio volumio[845]: info: [ASDebug] CurState: play PrevState: play Mar 11 17:20:02 volumio volumio[845]: info: [ASDebug] CurState: play PrevState: play Mar 11 17:20:02 volumio volumio[845]: info: [GPIO_Control] Status changed: play Mar 11 17:20:02 volumio volumio[845]: info: [GPIO_Control] Status changed: play Mar 11 17:20:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:05 volumio volumio[845]: info: CURURI: music-library Mar 11 17:20:05 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:07 volumio volumio[845]: info: CURURI: music-library/NAS Mar 11 17:20:07 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:07 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:08 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:08 volumio volumio[845]: info: CURURI: music-library/NAS/Scooby Mar 11 17:20:08 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:18 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:18 volumio volumio[845]: info: Listing playlists Mar 11 17:20:18 volumio volumio[845]: info: Listing playlists Mar 11 17:20:28 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:38 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:38 volumio volumio[845]: info: Listing playlists Mar 11 17:20:38 volumio volumio[845]: info: Listing playlists Mar 11 17:20:46 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party Mar 11 17:20:52 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:52 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party Mar 11 17:20:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:55 volumio volumio[845]: info: CURURI: music-library/NAS/Scooby/Tinsley Ellis Mar 11 17:20:55 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party Mar 11 17:20:55 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:20:58 volumio volumio[845]: info: CURURI: music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth Mar 11 17:20:58 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party Mar 11 17:20:58 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:20:58 volumio volumio[845]: info: Listing playlists Mar 11 17:20:58 volumio volumio[845]: info: Listing playlists Mar 11 17:20:59 volumio volumio[845]: info: Preload queue cleared Mar 11 17:20:59 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/08 Freeway Soul.mp3 Mar 11 17:20:59 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/01 Say Too Much.mp3 Mar 11 17:20:59 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/10 Sleep On It.mp3 Mar 11 17:20:59 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/03 Get to the Bottom.mp3 Mar 11 17:20:59 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/08 Freeway Soul.mp3 in service mpd Mar 11 17:20:59 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tinsley%20Ellis/Moment%20of%20Truth/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTinsley%20Ellis%2FMoment%20of%20Truth%2F08%20Freeway%20Soul.mp3&metadata=false Mar 11 17:20:59 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tinsley Ellis/Moment of Truth/08 Freeway Soul.mp3 Mar 11 17:20:59 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/01 Say Too Much.mp3 in service mpd Mar 11 17:20:59 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/10 Sleep On It.mp3 in service mpd Mar 11 17:20:59 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tinsley Ellis/Moment of Truth/03 Get to the Bottom.mp3 in service mpd Mar 11 17:20:59 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tinsley%20Ellis/Moment%20of%20Truth/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTinsley%20Ellis%2FMoment%20of%20Truth%2F01%20Say%20Too%20Much.mp3&metadata=false Mar 11 17:20:59 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tinsley Ellis/Moment of Truth/01 Say Too Much.mp3 Mar 11 17:20:59 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tinsley%20Ellis/Moment%20Of%20Truth/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTinsley%20Ellis%2FMoment%20of%20Truth%2F10%20Sleep%20On%20It.mp3&metadata=false Mar 11 17:20:59 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tinsley Ellis/Moment of Truth/10 Sleep On It.mp3 Mar 11 17:21:01 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tinsley%20Ellis/Moment%20of%20Truth/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTinsley%20Ellis%2FMoment%20of%20Truth%2F03%20Get%20to%20the%20Bottom.mp3&metadata=false Mar 11 17:21:01 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tinsley Ellis/Moment of Truth/03 Get to the Bottom.mp3 Mar 11 17:21:08 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:21:10 volumio volumio[845]: info: MPD Database updated - AlbumList cache refreshed Mar 11 17:21:10 volumio volumio[845]: info: Mar 11 17:21:10 volumio volumio[845]: ---------------------------- MPD announces state update: database Mar 11 17:21:10 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:10 volumio volumio[845]: info: Mar 11 17:21:10 volumio volumio[845]: ---------------------------- MPD announces state update: update Mar 11 17:21:10 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:21:10 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:10 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:21:10 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:21:12 volumio volumio[845]: info: CURURI: music-library/NAS/Scooby/Tool Mar 11 17:21:15 volumio volumio[845]: info: sendMpdCommand status took 5773 milliseconds Mar 11 17:21:15 volumio volumio[845]: info: sendMpdCommand status took 5774 milliseconds Mar 11 17:21:15 volumio volumio[845]: info: sendMpdCommand status took 5774 milliseconds Mar 11 17:21:15 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:21:15 volumio volumio[845]: info: Command Router : Notfying DB Updatefalse Mar 11 17:21:15 volumio volumio[845]: info: CoreCommandRouter::Close All Modals sent Mar 11 17:21:15 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:21:15 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 17:21:15 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:21:15 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 17:21:16 volumio volumio[845]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 11 17:21:16 volumio volumio[845]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 11 17:21:16 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Mar 11 17:21:16 volumio volumio[845]: info: ControllerMpd::pushState Mar 11 17:21:16 volumio volumio[845]: info: CoreCommandRouter::servicePushState Mar 11 17:21:16 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Mar 11 17:21:16 volumio volumio[845]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 11 17:21:16 volumio volumio[845]: info: ControllerMpd::pushState Mar 11 17:21:16 volumio volumio[845]: info: CoreCommandRouter::servicePushState Mar 11 17:21:16 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Mar 11 17:21:16 volumio volumio[845]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 11 17:21:16 volumio volumio[845]: info: ------------------------------ 5917ms Mar 11 17:21:16 volumio volumio[845]: info: ------------------------------ 5916ms Mar 11 17:21:16 volumio volumio[845]: info: Preload queue cleared Mar 11 17:21:17 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:21:17 volumio volumio[845]: info: Listing playlists Mar 11 17:21:17 volumio volumio[845]: info: Listing playlists Mar 11 17:21:19 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 11 17:21:19 volumio volumio[845]: info: CURURI: music-library/NAS/Scooby/Tool/Undertow Mar 11 17:21:19 volumio volumio[845]: info: Preload queue cleared Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/01 Intolerance.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/02 Prison Sex.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/03 Sober.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/04 Bottom.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/05 Crawl Away.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/06 Swamp Song.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/08 4°.mp3 Mar 11 17:21:19 volumio volumio[845]: info: Preloading song: music-library/NAS/Scooby/Tool/Undertow/09 Flood.mp3 Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/01 Intolerance.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/02 Prison Sex.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/03 Sober.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/05 Crawl Away.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/04 Bottom.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/06 Swamp Song.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/08 4°.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: Exploding uri music-library/NAS/Scooby/Tool/Undertow/09 Flood.mp3 in service mpd Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F01%20Intolerance.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/01 Intolerance.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F02%20Prison%20Sex.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/02 Prison Sex.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F03%20Sober.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/03 Sober.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F05%20Crawl%20Away.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/05 Crawl Away.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F04%20Bottom.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/04 Bottom.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F06%20Swamp%20Song.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/06 Swamp Song.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F07%20Undertow.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F08%204%C2%B0.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/08 4°.mp3 Mar 11 17:21:20 volumio volumio[845]: info: ALBUMART /albumart?cacheid=53&web=Tool/Undertow/extralarge&path=%2Fmnt%2FNAS%2FScooby%2FTool%2FUndertow%2F09%20Flood.mp3&metadata=false Mar 11 17:21:20 volumio volumio[845]: info: URI /mnt/NAS/Scooby/Tool/Undertow/09 Flood.mp3 Mar 11 17:21:26 volumio volumio[845]: info: Adding music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 to favourites using generic method Mar 11 17:21:26 volumio volumio[845]: info: Saving local item favourites Mar 11 17:21:26 volumio volumio[845]: info: Pushing Favourites {"uri":"/mnt/NAS/Scooby/Tool/Undertow/07 Undertow.mp3","favourite":false} Mar 11 17:21:28 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 11 17:21:29 volumio volumio[845]: info: Adding music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 to favourites using generic method Mar 11 17:21:30 volumio volumio[845]: info: Saving local item favourites Mar 11 17:21:30 volumio volumio[845]: info: Pushing Favourites {"uri":"/mnt/NAS/Scooby/Tool/Undertow/07 Undertow.mp3","favourite":false} Mar 11 17:21:32 volumio volumio[845]: info: Preload queue cleared Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::ClearQueue Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::stop Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::pushState Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::serviceStop Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::serviceStop Mar 11 17:21:32 volumio volumio[845]: info: [1741738892046] ControllerPandora::stop Mar 11 17:21:32 volumio volumio[845]: info: ControllerMpd::stop Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::saveQueue Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::addQueueItems Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::addQueueItems Mar 11 17:21:32 volumio volumio[845]: info: Preload queue cleared Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/01 Intolerance.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/01 Intolerance.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/02 Prison Sex.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/02 Prison Sex.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/03 Sober.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/03 Sober.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/04 Bottom.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/04 Bottom.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/05 Crawl Away.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/05 Crawl Away.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/06 Swamp Song.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/06 Swamp Song.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/07 Undertow.mp3 Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::saveQueue Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPlay Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::play index 6 Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::addQueueItems Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::addQueueItems Mar 11 17:21:32 volumio volumio[845]: info: Preload queue cleared Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/08 4°.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/08 4°.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/Scooby/Tool/Undertow/09 Flood.mp3 Mar 11 17:21:32 volumio volumio[845]: info: Using cached record of: music-library/NAS/Scooby/Tool/Undertow/09 Flood.mp3 Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::stop Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::saveQueue Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::play index undefined Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 6 Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 6 Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::clearAddPlayTracks NAS/Scooby/Tool/Undertow/07 Undertow.mp3 Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Mar 11 17:21:32 volumio volumio[845]: info: [ASDebug] CurState: stop PrevState: play Mar 11 17:21:32 volumio volumio[845]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 11 17:21:32 volumio volumio[845]: info: [GPIO_Control] Status changed: stop Mar 11 17:21:32 volumio volumio[845]: info: [GPIO_Control] Handling event: musicStop Mar 11 17:21:32 volumio volumio[845]: info: Mar 11 17:21:32 volumio volumio[845]: ---------------------------- MPD announces state update: player Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand stop took 583 milliseconds Mar 11 17:21:32 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand stop took 564 milliseconds Mar 11 17:21:32 volumio volumio[845]: info: ControllerMpd::clear Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Mar 11 17:21:32 volumio volumio[845]: info: Mar 11 17:21:32 volumio volumio[845]: ---------------------------- MPD announces system playlist update Mar 11 17:21:32 volumio volumio[845]: info: Ignoring MPD Status Update Mar 11 17:21:32 volumio volumio[845]: info: Mar 11 17:21:32 volumio volumio[845]: ---------------------------- MPD announces system playlist update Mar 11 17:21:32 volumio volumio[845]: info: Ignoring MPD Status Update Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand status took 6 milliseconds Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand clear took 6 milliseconds Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand clear took 6 milliseconds Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 17:21:32 volumio volumio[845]: info: [1741738892639] ControllerPandora::pushState Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::servicePushState Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::pushState Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 6 Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 17:21:32 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Mar 11 17:21:32 volumio volumio[845]: info: CorePlayQueue::getTrack 6 Mar 11 17:21:32 volumio volumio[845]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received pandora Mar 11 17:21:32 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "NAS/Scooby/Tool/Undertow/07 Undertow.mp3" Mar 11 17:21:32 volumio volumio[845]: error: updateQueue error: null Mar 11 17:21:32 volumio volumio[845]: error: updateQueue error: null Mar 11 17:21:32 volumio volumio[845]: info: ------------------------------ 13ms Mar 11 17:21:32 volumio volumio[845]: info: ------------------------------ 12ms Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Mar 11 17:21:32 volumio volumio[845]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 11 17:21:32 volumio volumio[845]: info: ------------------------------ 37ms Mar 11 17:21:32 volumio volumio[845]: info: [ASDebug] CurState: stop PrevState: play Mar 11 17:21:32 volumio volumio[845]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 11 17:21:32 volumio volumio[845]: info: [GPIO_Control] Status changed: stop Mar 11 17:21:32 volumio volumio[845]: info: Mar 11 17:21:32 volumio volumio[845]: ---------------------------- MPD announces system playlist update Mar 11 17:21:32 volumio volumio[845]: info: Ignoring MPD Status Update Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand add "NAS/Scooby/Tool/Undertow/07 Undertow.mp3" took 31 milliseconds Mar 11 17:21:32 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Mar 11 17:21:32 volumio volumio[845]: info: ------------------------------ 4ms Mar 11 17:21:32 volumio volumio[845]: info: sendMpdCommand play took 3 milliseconds Mar 11 17:21:33 volumio volumio[845]: info: Mar 11 17:21:33 volumio volumio[845]: ---------------------------- MPD announces state update: player Mar 11 17:21:33 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:21:33 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:33 volumio volumio[845]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 11 17:21:33 volumio volumio[845]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 11 17:21:33 volumio volumio[845]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 11 17:21:33 volumio volumio[845]: info: Mar 11 17:21:33 volumio volumio[845]: ---------------------------- MPD announces state update: player Mar 11 17:21:33 volumio volumio[845]: info: ControllerMpd::getState Mar 11 17:21:33 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Mar 11 17:21:33 volumio volumio[845]: info: sendMpdCommand status took 566 milliseconds Mar 11 17:21:33 volumio volumio[845]: verbose: ControllerMpd::parseState Mar 11 17:21:33 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 17:21:33 volumio volumio[845]: info: camilladsp spawned new process with pid 9836, instance 1, run: true Mar 11 17:21:33 volumio volumio[845]: info: [ASDebug] Togle GPIO: OFF Mar 11 17:21:33 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 17:21:33 volumio volumio[845]: Error: ESPIPE: invalid seek, write Mar 11 17:21:33 volumio volumio[845]: at Object.writeSync (fs.js:711:3) Mar 11 17:21:33 volumio volumio[845]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Mar 11 17:21:33 volumio volumio[845]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Mar 11 17:21:33 volumio volumio[845]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Mar 11 17:21:33 volumio volumio[845]: at listOnTimeout (internal/timers.js:554:17) Mar 11 17:21:33 volumio volumio[845]: at processTimers (internal/timers.js:497:7) { Mar 11 17:21:33 volumio volumio[845]: errno: -29, Mar 11 17:21:33 volumio volumio[845]: syscall: 'write', Mar 11 17:21:33 volumio volumio[845]: code: 'ESPIPE' Mar 11 17:21:33 volumio volumio[845]: } Mar 11 17:21:33 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 17:21:34 volumio sudo[9855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 17:20 Mar 11 17:21:34 volumio sudo[9855]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"