-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-03-29 16:54:09 CET. -- Mar 29 16:53:02 volumio go-librespot[1079]: time="2024-03-29T16:53:02+01:00" level=debug msg="fetched chunk 6/15, size: 524288" Mar 29 16:53:06 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Mar 29 16:53:06 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:09 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 16:53:10 volumio volumio[894]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 16:53:10 volumio volumio[894]: info: Received Get System Version Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 16:53:10 volumio volumio[894]: info: Received Get System Info Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:53:10 volumio volumio[894]: info: Discovery: Getting this device information Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Mar 29 16:53:10 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: info: Retrieving Cloud Streaming UI Mar 29 16:53:10 volumio volumio[894]: info: Getting Tidal Cloud Configuration Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: info: Getting Qobuz Cloud Configuration Mar 29 16:53:10 volumio volumio[894]: info: Asking plugin for UI Config Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: info: Getting Spotify Cloud Configuration Mar 29 16:53:10 volumio volumio[894]: info: Asking plugin for UI Config Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: info: Saving Spotify Acccount Mar 29 16:53:10 volumio volumio[894]: info: Got Tidal Cloud Configuration Mar 29 16:53:10 volumio volumio[894]: info: Got it Mar 29 16:53:10 volumio volumio[894]: info: Got it Mar 29 16:53:10 volumio volumio[894]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 16:53:10 volumio volumio[894]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 16:53:10 volumio volumio[894]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 16:53:11 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 16:53:11 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand update Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: info: Mar 29 16:53:14 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand update took 9 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: info: Mar 29 16:53:14 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: info: Mar 29 16:53:14 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 9 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 6 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 6 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds Mar 29 16:53:14 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: info: Command Router : Notfying DB Updatetrue Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Mar 29 16:53:14 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:14 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:14 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:14 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:14 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:14 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:14 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:14 volumio volumio[894]: info: ------------------------------ 613ms Mar 29 16:53:14 volumio volumio[894]: info: ------------------------------ 609ms Mar 29 16:53:14 volumio volumio[894]: info: ------------------------------ 608ms Mar 29 16:53:14 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":46908,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:14 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":46908,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:14 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":46908,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:14 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:14 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:14 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:15 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 16:53:16 volumio go-librespot[1079]: time="2024-03-29T16:53:16+01:00" level=debug msg="fetched chunk 7/15, size: 524288" Mar 29 16:53:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 16:53:20 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Mar 29 16:53:20 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:21 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue Mar 29 16:53:21 volumio volumio[894]: info: CoreStateMachine::getQueue Mar 29 16:53:21 volumio volumio[894]: info: CorePlayQueue::getQueue Mar 29 16:53:22 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:53:22 volumio volumio[894]: info: CURURI: music-library Mar 29 16:53:22 volumio volumio[894]: info: Preload queue cleared Mar 29 16:53:25 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:53:25 volumio volumio[894]: info: CURURI: music-library/USB Mar 29 16:53:25 volumio volumio[894]: info: Preload queue cleared Mar 29 16:53:28 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:53:28 volumio volumio[894]: info: CURURI: music-library/USB/MAXELL_500 Mar 29 16:53:28 volumio volumio[894]: info: Preload queue cleared Mar 29 16:53:29 volumio go-librespot[1079]: time="2024-03-29T16:53:29+01:00" level=debug msg="fetched chunk 8/15, size: 524288" Mar 29 16:53:30 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:53:30 volumio volumio[894]: info: CURURI: music-library/USB/MAXELL_500/FLAC Mar 29 16:53:30 volumio volumio[894]: info: Preload queue cleared Mar 29 16:53:30 volumio volumio[894]: info: Preloading song: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Lp Side 12.wav Mar 29 16:53:30 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Lp Side 12.wav in service mpd Mar 29 16:53:30 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=//extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2FSantana%20-%20Supernatural%20Lp%20Side%201%EF%80%A22.wav&metadata=false Mar 29 16:53:30 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/Santana - Supernatural Lp Side 12.wav Mar 29 16:53:33 volumio volumio[894]: info: MPD Database updated - AlbumList cache refreshed Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: database Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: MPD Database updated - AlbumList cache refreshed Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: database Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: MPD Database updated - AlbumList cache refreshed Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: database Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: Mar 29 16:53:33 volumio volumio[894]: ---------------------------- MPD announces state update: update Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: ControllerMpd::getState Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:53:33 volumio volumio[894]: info: sendMpdCommand status took 95 milliseconds Mar 29 16:53:33 volumio volumio[894]: info: sendMpdCommand status took 94 milliseconds Mar 29 16:53:33 volumio volumio[894]: info: sendMpdCommand status took 94 milliseconds Mar 29 16:53:33 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3199ms Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3200ms Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3241 milliseconds Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3240 milliseconds Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3240 milliseconds Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3284ms Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3285ms Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3354 milliseconds Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3354 milliseconds Mar 29 16:53:36 volumio volumio[894]: info: sendMpdCommand status took 3354 milliseconds Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: Command Router : Notfying DB Updatefalse Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::Close All Modals sent Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: verbose: ControllerMpd::parseState Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ControllerMpd::pushState Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:53:36 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:53:36 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:53:36 volumio volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3400ms Mar 29 16:53:36 volumio volumio[894]: info: ------------------------------ 3399ms Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:36 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:53:43 volumio go-librespot[1079]: time="2024-03-29T16:53:43+01:00" level=debug msg="fetched chunk 9/15, size: 524288" Mar 29 16:53:43 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:53:43 volumio volumio[894]: info: CURURI: music-library/USB/Maxell_500Gb Mar 29 16:53:43 volumio volumio[894]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 29 16:53:43 volumio volumio[894]: info: Preload queue cleared Mar 29 16:53:50 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/USB/Maxell_500Gb Mar 29 16:53:50 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 16:53:50 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Mar 29 16:53:50 volumio volumio[894]: info: Command failed: /usr/bin/sudo /bin/umount /mnt/USB/Maxell_500Gb Mar 29 16:53:50 volumio volumio[894]: umount: /mnt/USB/Maxell_500Gb: not mounted. Mar 29 16:53:50 volumio volumio[894]: {"killed":false,"code":32,"signal":null,"cmd":"/usr/bin/sudo /bin/umount /mnt/USB/Maxell_500Gb"} Mar 29 16:53:55 volumio go-librespot[1079]: time="2024-03-29T16:53:55+01:00" level=debug msg="fetched chunk 10/15, size: 524288" Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::ClearQueue Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::stop Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrackBlock Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::serviceStop Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::serviceStop Mar 29 16:54:00 volumio volumio[894]: info: Spotify Stop Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: SPOTIFY STOP Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: {"status":"play","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":66157,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Mar 29 16:54:00 volumio volumio[894]: info: Sending Spotify command to local API: /player/pause Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::saveQueue Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::addQueueItems Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::addQueueItems Mar 29 16:54:00 volumio volumio[894]: info: Preload queue cleared Mar 29 16:54:00 volumio volumio[894]: info: Adding Item to queue: music-library/USB/Maxell_500Gb Mar 29 16:54:00 volumio volumio[894]: info: Exploding uri music-library/USB/Maxell_500Gb in service mpd Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::saveQueue Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrackBlock Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioPlay Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::play index 0 Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::stop Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::play index undefined Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:54:00 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"II MOST WANTED","artist":"Beyoncé","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d0000b273d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","codec":"ogg","seek":0,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:54:00 volumio go-librespot[1079]: time="2024-03-29T16:54:00+01:00" level=debug msg="pause track at 93811ms" Mar 29 16:54:00 volumio go-librespot[1079]: time="2024-03-29T16:54:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 16:54:00 volumio go-librespot[1079]: time="2024-03-29T16:54:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 16:54:00 volumio go-librespot[1079]: time="2024-03-29T16:54:00+01:00" level=trace msg="emitting websocket event: paused" Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","play_origin":"go-librespot"}} Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: PUSH STATE SPOTIFY Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: {"status":"pause","service":"spop","title":"II MOST WANTED","artist":"Beyoncé, Miley Cyrus","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d00001e02d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","seek":90000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::servicePushState Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:54:00 volumio volumio[894]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"II MOST WANTED","artist":"Beyoncé, Miley Cyrus","album":"COWBOY CARTER","albumart":"https://i.scdn.co/image/ab67616d00001e02d9e3a369022053e02b4db2a2","uri":"spotify:track:1UP7PXne1lIYZB5G5aiRGL","trackType":"spotify","seek":90000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 29 16:54:00 volumio volumio[894]: verbose: CURRENT POSITION 0 Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::syncState stateService pause Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop Mar 29 16:54:00 volumio volumio[894]: info: CoreStateMachine::pushState Mar 29 16:54:00 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:54:00 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Mar 29 16:54:00 volumio volumio[894]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.0.120","model":"TX-NR686"} Mar 29 16:54:00 volumio volumio[894]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 16:54:02 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:54:02 volumio volumio[894]: info: CURURI: music-library/USB/MAXELL_500 Mar 29 16:54:02 volumio volumio[894]: info: Preload queue cleared Mar 29 16:54:05 volumio volumio[894]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Mar 29 16:54:05 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 16:54:05 volumio volumio[894]: info: CURURI: music-library/USB/MAXELL_500/FLAC Mar 29 16:54:05 volumio volumio[894]: info: Preload queue cleared Mar 29 16:54:05 volumio volumio[894]: info: Preloading song: music-library/USB/MAXELL_500/FLAC/Joe Bonamassa Side 12.wav Mar 29 16:54:05 volumio volumio[894]: info: Preloading song: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Side 11.wav Mar 29 16:54:05 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Joe Bonamassa Side 12.wav in service mpd Mar 29 16:54:05 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=//extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2FJoe%20Bonamassa%20Side%201%EF%80%A22.wav&metadata=false Mar 29 16:54:05 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/Joe Bonamassa Side 12.wav Mar 29 16:54:05 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Side 11.wav in service mpd Mar 29 16:54:05 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=//extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2FSantana%20-%20Supernatural%20Side%201%EF%80%A21.wav&metadata=false Mar 29 16:54:05 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/Santana - Supernatural Side 11.wav Mar 29 16:54:08 volumio volumio[894]: info: Preload queue cleared Mar 29 16:54:08 volumio volumio[894]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 16:54:08 volumio volumio[894]: info: CoreStateMachine::ClearQueue Mar 29 16:54:08 volumio volumio[894]: info: CoreStateMachine::stop Mar 29 16:54:08 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:54:08 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue Mar 29 16:54:08 volumio volumio[894]: info: CorePlayQueue::saveQueue Mar 29 16:54:08 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:54:08 volumio volumio[894]: info: CoreStateMachine::addQueueItems Mar 29 16:54:08 volumio volumio[894]: info: CorePlayQueue::addQueueItems Mar 29 16:54:08 volumio volumio[894]: info: Preload queue cleared Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Joe Bonamassa Side 12.wav Mar 29 16:54:08 volumio volumio[894]: info: Using cached record of: music-library/USB/MAXELL_500/FLAC/Joe Bonamassa Side 12.wav Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Lp Side 12.wav Mar 29 16:54:08 volumio volumio[894]: info: Using cached record of: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Lp Side 12.wav Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Side 11.wav Mar 29 16:54:08 volumio volumio[894]: info: Using cached record of: music-library/USB/MAXELL_500/FLAC/Santana - Supernatural Side 11.wav Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007) Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007) in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96) Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96) in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Boz Scaggs - Dig - 2001 Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Boz Scaggs - Dig - 2001 in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Depeche Mode - 2023 - Memento Mori (24bit-96kHz) Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Depeche Mode - 2023 - Memento Mori (24bit-96kHz) in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Elvin Jones - Merry-Go-Round (1972) [192-24] Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Elvin Jones - Merry-Go-Round (1972) [192-24] in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Elvin Jones Jazz Machine - At Onkel Pö´s Carnegie Hall, Hamburg - 1981-2020 (24-48) Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Elvin Jones Jazz Machine - At Onkel Pö´s Carnegie Hall, Hamburg - 1981-2020 (24-48) in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Nickelback ALAC Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Nickelback ALAC in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Tom Russell - 1999 - The Man From God Knows Where Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Tom Russell - 1999 - The Man From God Knows Where in service mpd Mar 29 16:54:08 volumio volumio[894]: info: Adding Item to queue: music-library/USB/MAXELL_500/FLAC/Toto - All In 1978 - 2018 (24-96 FLAC) [2018 All In Box Set Vinyl] Mar 29 16:54:08 volumio volumio[894]: info: Exploding uri music-library/USB/MAXELL_500/FLAC/Toto - All In 1978 - 2018 (24-96 FLAC) [2018 All In Box Set Vinyl] in service mpd Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F01%20-%20Can't%20Lose%20What%20You%20Never%20Had.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/01 - Can't Lose What You Never Had.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F02%20-%20Just%20Another%20Love%20Song.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/02 - Just Another Love Song.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F03%20-%20Nevertheless.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/03 - Nevertheless.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F04%20-%20Win%2C%20Lose%20or%20Draw.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/04 - Win, Lose or Draw.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F05%20-%20Louisiana%20Lou%20and%20Three%20Card%20Monty%20John.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/05 - Louisiana Lou and Three Card Monty John.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F06%20-%20High%20Falls.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/06 - High Falls.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=The%20Allman%20Brothers%20Band/Win%2C%20Lose%20or%20Draw/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1975%20Win%2C%20Lose%20Or%20Draw%20(PHCR-94007)%2F07%20-%20Sweet%20Mama.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1975 Win, Lose Or Draw (PHCR-94007)/07 - Sweet Mama.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F01%20-%20Second%20Hand%20News.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/01 - Second Hand News.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F02%20-%20Dreams.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/02 - Dreams.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F03%20-%20Never%20Going%20Back%20Again.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/03 - Never Going Back Again.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F04%20-%20Don't%20Stop.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/04 - Don't Stop.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F05%20-%20Go%20Your%20Own%20Way.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/05 - Go Your Own Way.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F06%20-%20Songbird.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/06 - Songbird.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F07%20-%20The%20Chain.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/07 - The Chain.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F08%20-%20You%20Make%20Loving%20Fun.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/08 - You Make Loving Fun.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F09%20-%20I%20Don't%20Want%20To%20Know.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/09 - I Don't Want To Know.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F10%20-%20Oh%20Daddy.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/10 - Oh Daddy.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F11%20-%20Gold%20Dust%20Woman.flac&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/11 - Gold Dust Woman.flac Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours.cue&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/1977 - Fleetwood Mac - Rumours.cue Mar 29 16:54:08 volumio volumio[894]: info: ALBUMART /albumart?cacheid=299&web=Fleetwood%20Mac/Rumours/extralarge&path=%2Fmnt%2FUSB%2FMAXELL_500%2FFLAC%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours%20(MB%2C%20Warner%20Bros.%2C%20WST%203010-C%2C%20US%2C%2024-96)%2F1977%20-%20Fleetwood%20Mac%20-%20Rumours.cue&metadata=false Mar 29 16:54:08 volumio volumio[894]: info: URI /mnt/USB/MAXELL_500/FLAC/1977 - Fleetwood Mac - Rumours (MB, Warner Bros., WST 3010-C, US, 24-96)/1977 - Fleetwood Mac - Rumours.cue Mar 29 16:54:08 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 16:54:08 volumio volumio[894]: Error: Unable to resolve or reject the same promise twice Mar 29 16:54:08 volumio volumio[894]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 16:54:08 volumio volumio[894]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Mar 29 16:54:08 volumio volumio[894]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 16:54:08 volumio volumio[894]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 16:54:08 volumio volumio[894]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 16:54:08 volumio volumio[894]: at Socket.emit (events.js:315:20) Mar 29 16:54:08 volumio volumio[894]: at addChunk (internal/streams/readable.js:309:12) Mar 29 16:54:08 volumio volumio[894]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 29 16:54:08 volumio volumio[894]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 29 16:54:08 volumio volumio[894]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 29 16:54:08 volumio volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 16:54:09 volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 16:53 Mar 29 16:54:09 volumio sudo[1342]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"