-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-09-06 14:26:41 UTC. -- Sep 06 14:25:09 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:09 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:10 yoda volumio[828]: info: CoreCommandRouter::volumioGetState Sep 06 14:25:10 yoda volumio[828]: info: CorePlayQueue::getTrack 0 Sep 06 14:25:10 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:12 yoda volumio[828]: info: CoreCommandRouter::volumioGetQueue Sep 06 14:25:12 yoda volumio[828]: info: CoreStateMachine::getQueue Sep 06 14:25:12 yoda volumio[828]: info: CorePlayQueue::getQueue Sep 06 14:25:12 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:13 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:18 yoda volumio[828]: info: CoreCommandRouter::volumioGetState Sep 06 14:25:18 yoda volumio[828]: info: CorePlayQueue::getTrack 0 Sep 06 14:25:23 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:24 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:28 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:28 yoda volumio[828]: info: CoreCommandRouter::volumioGetState Sep 06 14:25:28 yoda volumio[828]: info: CorePlayQueue::getTrack 0 Sep 06 14:25:29 yoda volumio[1196]: [Vollibrespot] : couldn't parse packet from 192.168.0.168:5353: query type 65 is invalid Sep 06 14:25:33 yoda volumio[828]: info: CoreCommandRouter::volumioGetState Sep 06 14:25:33 yoda volumio[828]: info: CorePlayQueue::getTrack 0 Sep 06 14:25:37 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 14:25:37 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 0 Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: Prefetching next song Sep 06 14:26:08 yoda volumio[828]: info: DOING PREFETCH IN MPD Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand add "NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a" Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:08 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand add "NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a" took 3 milliseconds Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces state update: options Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 4ms Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand consume 1 took 2 milliseconds Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:08 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:08 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand status took 6 milliseconds Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 5ms Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 3ms Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces state update: options Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:08 yoda volumio[828]: info: Sep 06 14:26:08 yoda volumio[828]: ---------------------------- MPD announces state update: options Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand status took 4 milliseconds Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand status took 3 milliseconds Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::startPlaybackTimer Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":308834,"duration":338,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Warm Leatherette","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/01 Warm Leatherette.m4a","trackType":"m4a"} Sep 06 14:26:08 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:08 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 48ms Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand playlistinfo took 33 milliseconds Sep 06 14:26:08 yoda volumio[828]: info: sendMpdCommand playlistinfo took 33 milliseconds Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:08 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":308834,"duration":338,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Warm Leatherette","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/01 Warm Leatherette.m4a","trackType":"m4a"} Sep 06 14:26:08 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:08 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":308834,"duration":338,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Warm Leatherette","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/01 Warm Leatherette.m4a","trackType":"m4a"} Sep 06 14:26:08 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:08 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:08 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:08 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 95ms Sep 06 14:26:08 yoda volumio[828]: info: ------------------------------ 94ms Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:08 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 21 Sep 06 14:26:09 yoda volumio[828]: q: .5 Sep 06 14:26:09 yoda volumio[828]: gain: 10 Sep 06 14:26:09 yoda volumio[828]: eq2: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 66 Sep 06 14:26:09 yoda volumio[828]: q: 2 Sep 06 14:26:09 yoda volumio[828]: gain: 1.1 Sep 06 14:26:09 yoda volumio[828]: eq3: Sep 06 14:26:09 yoda volumio[828]: type: Biquad Sep 06 14:26:09 yoda volumio[828]: parameters: Sep 06 14:26:09 yoda volumio[828]: type: Peaking Sep 06 14:26:09 yoda volumio[828]: freq: 190 Sep 06 14:26:09 yoda volumio[828]: q: 1.2 Sep 06 14:26:09 yoda volumio[828]: gain: -1.6 Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:09 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:16 yoda volumio[828]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 06 14:26:18 yoda volumio[828]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 06 14:26:18 yoda volumio[828]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 06 14:26:18 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 14:26:18 yoda volumio[828]: [SpotifyConnect] Closing metadata listener Sep 06 14:26:18 yoda sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Sep 06 14:26:18 yoda sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:18 yoda systemd[1]: Stopping Volspotconnect2 Daemon... Sep 06 14:26:18 yoda systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Sep 06 14:26:18 yoda systemd[1]: volspotconnect.service: Succeeded. Sep 06 14:26:18 yoda systemd[1]: Stopped Volspotconnect2 Daemon. Sep 06 14:26:18 yoda sudo[1221]: pam_unix(sudo:session): session closed for user root Sep 06 14:26:18 yoda volumio[828]: [SpotifyConnect] Vollibrespot Daemon service stoped! Sep 06 14:26:19 yoda volumio[828]: info: Check plugin dependencies Sep 06 14:26:19 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 14:26:19 yoda volumio[828]: info: Rename folder Sep 06 14:26:19 yoda volumio[828]: info: Folder /tmp/downloaded_plugin.zip removed Sep 06 14:26:19 yoda volumio[828]: info: Move to category Sep 06 14:26:21 yoda volumio[828]: info: Checking if install.sh is present Sep 06 14:26:21 yoda volumio[828]: info: Executing install.sh Sep 06 14:26:21 yoda sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Sep 06 14:26:21 yoda sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:21 yoda volumio[828]: info: Installing Go-librespot Sep 06 14:26:21 yoda volumio[828]: info: Checking old vollibrespot installs Sep 06 14:26:21 yoda systemd[1]: Reloading. Sep 06 14:26:21 yoda systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 06 14:26:22 yoda systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 06 14:26:22 yoda systemd[1]: Reloading. Sep 06 14:26:22 yoda systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 06 14:26:22 yoda volumio[828]: info: Clearing old vollibrespot Sep 06 14:26:23 yoda volumio[828]: info: vollibrespot cleared Sep 06 14:26:23 yoda volumio[828]: info: Dowloading daemon Sep 06 14:26:24 yoda volumio[828]: info: Creating Start Script Sep 06 14:26:24 yoda systemd[1]: Reloading. Sep 06 14:26:24 yoda systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 06 14:26:25 yoda sudo[1235]: pam_unix(sudo:session): session closed for user root Sep 06 14:26:25 yoda volumio[828]: Plugin install end detected on script Sep 06 14:26:25 yoda volumio[828]: info: Install script completed Sep 06 14:26:25 yoda volumio[828]: info: Done installing plugin. Sep 06 14:26:25 yoda volumio[828]: info: Enabling plugin spop Sep 06 14:26:25 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 14:26:25 yoda volumio[828]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.48","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false}] Sep 06 14:26:25 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 14:26:25 yoda volumio[828]: info: Folder /tmp/plugins removed Sep 06 14:26:25 yoda volumio[828]: info: Folder /tmp/downloaded_plugin.zip removed Sep 06 14:26:25 yoda volumio[828]: info: Folder /data/temp removed Sep 06 14:26:36 yoda volumio[828]: info: Enabling plugin spop Sep 06 14:26:36 yoda volumio[828]: info: Loading plugin "spop"... Sep 06 14:26:36 yoda volumio[828]: info: PLUGIN START: spop Sep 06 14:26:36 yoda volumio[828]: info: Checking for old spotify connect plugin installed Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 14:26:36 yoda volumio[828]: [SpotifyConnect] Creating VLS config file Sep 06 14:26:36 yoda volumio[828]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:36 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:36 yoda volumio[828]: [SpotifyConnect] Starting metadata listener Sep 06 14:26:36 yoda volumio[828]: (node:828) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Sep 06 14:26:36 yoda volumio[828]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2578:27) Sep 06 14:26:36 yoda volumio[828]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2441:14) Sep 06 14:26:36 yoda volumio[828]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Sep 06 14:26:36 yoda volumio[828]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Sep 06 14:26:36 yoda volumio[828]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 06 14:26:36 yoda volumio[828]: (Use `node --trace-warnings ...` to show where the warning was created) Sep 06 14:26:36 yoda volumio[828]: (node:828) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Sep 06 14:26:36 yoda volumio[828]: (node:828) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Sep 06 14:26:36 yoda sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Sep 06 14:26:36 yoda sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:36 yoda volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 06 14:26:36 yoda systemd[1]: Started Volspotconnect2 Daemon. Sep 06 14:26:36 yoda sudo[1295]: pam_unix(sudo:session): session closed for user root Sep 06 14:26:36 yoda volumio[1297]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Sep 06 14:26:36 yoda systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Sep 06 14:26:36 yoda systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Sep 06 14:26:36 yoda volumio[828]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 06 14:26:36 yoda volumio[828]: [Metrics] SpotifyConnect: 0s 93.04ms Sep 06 14:26:36 yoda volumio[828]: info: Done. Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:37 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 5ms Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 4 milliseconds Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:37 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces system playlist update Sep 06 14:26:37 yoda volumio[828]: info: Ignoring MPD Status Update Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 10 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 8ms Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 6 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 6ms Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 5 milliseconds Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 46ms Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: Sep 06 14:26:37 yoda volumio[828]: ---------------------------- MPD announces state update: player Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::getState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand status Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 35 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 34 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 33 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 5 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand status took 3 milliseconds Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseState Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 115ms Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 111ms Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 110ms Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 96 milliseconds Sep 06 14:26:37 yoda volumio[828]: info: sendMpdCommand playlistinfo took 96 milliseconds Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: verbose: ControllerMpd::parseTrackInfo Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ControllerMpd::pushState Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::servicePushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":379,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Private Life","artist":"Grace Jones","album":"Warm Leatherette","uri":"NAS/Library/Grace Jones/Warm Leatherette/02 Private Life.m4a","trackType":"m4a"} Sep 06 14:26:37 yoda volumio[828]: verbose: CURRENT POSITION 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState stateService play Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::syncState currentStatus play Sep 06 14:26:37 yoda volumio[828]: info: Received an update from plugin. extracting info from payload Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: CoreStateMachine::pushState Sep 06 14:26:37 yoda volumio[828]: info: CorePlayQueue::getTrack 1 Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioPushState Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 153ms Sep 06 14:26:37 yoda volumio[828]: info: ------------------------------ 152ms Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 06 14:26:37 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 14:26:37 yoda volumio[828]: [SpotifyConnect] Closing metadata listener Sep 06 14:26:37 yoda volumio[828]: info: Disabling plugin spop Sep 06 14:26:37 yoda volumio[828]: info: Done. Sep 06 14:26:37 yoda sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Sep 06 14:26:37 yoda sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda volumio[828]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 14:26:37 yoda systemd[1]: Stopped Volspotconnect2 Daemon. Sep 06 14:26:37 yoda sudo[1301]: pam_unix(sudo:session): session closed for user root Sep 06 14:26:37 yoda volumio[828]: [SpotifyConnect] Vollibrespot Daemon service stoped! Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - eq1: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 21 Sep 06 14:26:38 yoda volumio[828]: q: .5 Sep 06 14:26:38 yoda volumio[828]: gain: 10 Sep 06 14:26:38 yoda volumio[828]: eq2: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 66 Sep 06 14:26:38 yoda volumio[828]: q: 2 Sep 06 14:26:38 yoda volumio[828]: gain: 1.1 Sep 06 14:26:38 yoda volumio[828]: eq3: Sep 06 14:26:38 yoda volumio[828]: type: Biquad Sep 06 14:26:38 yoda volumio[828]: parameters: Sep 06 14:26:38 yoda volumio[828]: type: Peaking Sep 06 14:26:38 yoda volumio[828]: freq: 190 Sep 06 14:26:38 yoda volumio[828]: q: 1.2 Sep 06 14:26:38 yoda volumio[828]: gain: -1.6 Sep 06 14:26:38 yoda volumio[828]: info: Enabling plugin spop Sep 06 14:26:38 yoda volumio[828]: info: Loading plugin "spop"... Sep 06 14:26:38 yoda volumio[828]: info: PLUGIN START: spop Sep 06 14:26:38 yoda volumio[828]: info: Checking for old spotify connect plugin installed Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 14:26:38 yoda volumio[828]: [SpotifyConnect] Creating VLS config file Sep 06 14:26:38 yoda volumio[828]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:38 yoda volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 14:26:38 yoda volumio[828]: [SpotifyConnect] Starting metadata listener Sep 06 14:26:38 yoda volumio[828]: (node:828) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Sep 06 14:26:38 yoda volumio[828]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2578:27) Sep 06 14:26:38 yoda volumio[828]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2441:14) Sep 06 14:26:38 yoda volumio[828]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Sep 06 14:26:38 yoda volumio[828]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Sep 06 14:26:38 yoda volumio[828]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 06 14:26:38 yoda volumio[828]: (node:828) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 14:26:38 yoda volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 06 14:26:38 yoda systemd[1]: Started Volspotconnect2 Daemon. Sep 06 14:26:38 yoda sudo[1316]: pam_unix(sudo:session): session closed for user root Sep 06 14:26:38 yoda volumio[828]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 06 14:26:38 yoda volumio[1318]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Sep 06 14:26:38 yoda volumio[828]: [Metrics] SpotifyConnect: 0s 83.17ms Sep 06 14:26:38 yoda volumio[828]: info: Done. Sep 06 14:26:38 yoda systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Sep 06 14:26:38 yoda systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Sep 06 14:26:40 yoda volumio[828]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 14:26:40 yoda volumio[828]: error: Cannot populate Spotify configuration: TypeError: Cannot read property 'bars' of undefined Sep 06 14:26:40 yoda volumio[828]: info: Checking Spotify Web API Sep 06 14:26:40 yoda volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 14:26:40 yoda volumio[828]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Sep 06 14:26:40 yoda volumio[828]: at healthCheck (dgram.js:897:11) Sep 06 14:26:40 yoda volumio[828]: at Socket.send (dgram.js:622:3) Sep 06 14:26:40 yoda volumio[828]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Sep 06 14:26:40 yoda volumio[828]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2354:23) Sep 06 14:26:40 yoda volumio[828]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2446:18) Sep 06 14:26:40 yoda volumio[828]: at listOnTimeout (internal/timers.js:554:17) Sep 06 14:26:40 yoda volumio[828]: at processTimers (internal/timers.js:497:7) { Sep 06 14:26:40 yoda volumio[828]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Sep 06 14:26:40 yoda volumio[828]: } Sep 06 14:26:40 yoda volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 14:26:41 yoda systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Sep 06 14:26:41 yoda systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Sep 06 14:26:41 yoda sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 14:25 Sep 06 14:26:41 yoda systemd[1]: Stopped Volspotconnect2 Daemon. Sep 06 14:26:41 yoda sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 14:26:41 yoda systemd[1]: Started Volspotconnect2 Daemon. Sep 06 14:26:41 yoda volumio[1330]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Sep 06 14:26:41 yoda systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Sep 06 14:26:41 yoda systemd[1]: volspotconnect.service: Failed with result 'exit-code'. 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="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"