-- Logs begin at Sat 2024-04-13 00:33:14 CEST, end at Sat 2024-04-13 10:40:02 CEST. -- Apr 13 10:39:12 volumiobe volumio[961]: info: CoreCommandRouter::volumioGetState Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 13 10:39:17 volumiobe volumio[961]: info: Getting Alsa Cards List without I2S DAC Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 13 10:39:17 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 13 10:39:27 volumiobe volumio[961]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Apr 13 10:39:27 volumiobe sudo[6830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 10:39:27 volumiobe sudo[6830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 10:39:27 volumiobe sudo[6830]: pam_unix(sudo:session): session closed for user root Apr 13 10:39:27 volumiobe volumio[961]: info: MPD Permissions set Apr 13 10:39:27 volumiobe sudo[6833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 10:39:27 volumiobe sudo[6833]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:27 volumiobe systemd[1]: Stopping Music Player Daemon... Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 10:39:27 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:28 volumiobe systemd[1]: mpd.service: Succeeded. Apr 13 10:39:28 volumiobe systemd[1]: Stopped Music Player Daemon. Apr 13 10:39:29 volumiobe systemd[1]: Starting Music Player Daemon... Apr 13 10:39:30 volumiobe mpd[6848]: Apr 13 10:39 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 13 10:39:30 volumiobe mpd[6848]: Apr 13 10:39 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 13 10:39:30 volumiobe mpd[6848]: Apr 13 10:39 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 13 10:39:30 volumiobe systemd[1]: Started Music Player Daemon. Apr 13 10:39:30 volumiobe sudo[6833]: pam_unix(sudo:session): session closed for user root Apr 13 10:39:30 volumiobe volumio[961]: error: updateQueue error: null Apr 13 10:39:36 volumiobe volumio[961]: info: CoreCommandRouter::volumioStop Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::stop Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::updateTrackBlock Apr 13 10:39:36 volumiobe volumio[961]: info: CorePlayQueue::getTrackBlock Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::pushState Apr 13 10:39:36 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:36 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:39:36 volumiobe volumio[961]: info: CoreCommandRouter::volumioPushState Apr 13 10:39:36 volumiobe volumio[961]: info: CoreStateMachine::serviceStop Apr 13 10:39:36 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:36 volumiobe volumio[961]: info: CoreCommandRouter::serviceStop Apr 13 10:39:36 volumiobe volumio[961]: info: [1712997576433] ControllerWebradio::stop Apr 13 10:39:36 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:39:36 volumiobe volumio[961]: info: [ASDebug] CurState: stop PrevState: play Apr 13 10:39:36 volumiobe volumio[961]: info: [ASDebug] InitTimeout - Amp off in: 2 ms Apr 13 10:39:36 volumiobe volumio[961]: info: sendMpdCommand stop took 42 milliseconds Apr 13 10:39:36 volumiobe volumio[961]: info: [ASDebug] Togle GPIO: OFF Apr 13 10:39:37 volumiobe volumio[961]: info: CoreCommandRouter::volumioPlay Apr 13 10:39:37 volumiobe volumio[961]: info: CoreStateMachine::play index undefined Apr 13 10:39:37 volumiobe volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:39:37 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:37 volumiobe volumio[961]: info: CoreStateMachine::startPlaybackTimer Apr 13 10:39:37 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:37 volumiobe volumio[961]: info: [1712997577174] ControllerWebradio::clearAddPlayTrack Apr 13 10:39:37 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:39:37 volumiobe volumio[961]: info: sendMpdCommand stop took 1 milliseconds Apr 13 10:39:37 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 10:39:37 volumiobe volumio[961]: info: Apr 13 10:39:37 volumiobe volumio[961]: ---------------------------- MPD announces system playlist update Apr 13 10:39:37 volumiobe volumio[961]: info: Ignoring MPD Status Update Apr 13 10:39:37 volumiobe volumio[961]: info: sendMpdCommand clear took 3 milliseconds Apr 13 10:39:37 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand load "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" Apr 13 10:39:37 volumiobe volumio[961]: error: updateQueue error: null Apr 13 10:39:37 volumiobe volumio[961]: info: ------------------------------ 6ms Apr 13 10:39:38 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand add "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" Apr 13 10:39:38 volumiobe volumio[961]: info: Apr 13 10:39:38 volumiobe volumio[961]: ---------------------------- MPD announces system playlist update Apr 13 10:39:38 volumiobe volumio[961]: info: Ignoring MPD Status Update Apr 13 10:39:38 volumiobe volumio[961]: info: sendMpdCommand add "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" took 2 milliseconds Apr 13 10:39:38 volumiobe volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:39:38 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand play Apr 13 10:39:38 volumiobe volumio[961]: info: ------------------------------ 4ms Apr 13 10:39:38 volumiobe volumio[961]: info: sendMpdCommand play took 4 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: info: CoreCommandRouter::volumioPlay Apr 13 10:39:39 volumiobe volumio[961]: info: CoreStateMachine::play index undefined Apr 13 10:39:39 volumiobe volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:39:39 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:39 volumiobe volumio[961]: info: CoreStateMachine::startPlaybackTimer Apr 13 10:39:39 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:39 volumiobe volumio[961]: info: [1712997579890] ControllerWebradio::clearAddPlayTrack Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:39:39 volumiobe volumio[961]: info: Apr 13 10:39:39 volumiobe volumio[961]: ---------------------------- MPD announces state update: player Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand stop took 9 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: info: ControllerMpd::getState Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand status took 13 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand clear took 14 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::parseState Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand load "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" Apr 13 10:39:39 volumiobe volumio[961]: info: Apr 13 10:39:39 volumiobe volumio[961]: ---------------------------- MPD announces system playlist update Apr 13 10:39:39 volumiobe volumio[961]: info: Ignoring MPD Status Update Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:39:39 volumiobe volumio[961]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 13 10:39:39 volumiobe volumio[961]: info: ------------------------------ 24ms Apr 13 10:39:39 volumiobe volumio[961]: error: updateQueue error: null Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand add "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" Apr 13 10:39:39 volumiobe volumio[961]: info: ------------------------------ 61ms Apr 13 10:39:39 volumiobe volumio[961]: info: Apr 13 10:39:39 volumiobe volumio[961]: ---------------------------- MPD announces system playlist update Apr 13 10:39:39 volumiobe volumio[961]: info: Ignoring MPD Status Update Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand add "http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:" took 2 milliseconds Apr 13 10:39:39 volumiobe volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:39:39 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand play Apr 13 10:39:39 volumiobe volumio[961]: info: ------------------------------ 2ms Apr 13 10:39:39 volumiobe volumio[961]: info: sendMpdCommand play took 2 milliseconds Apr 13 10:39:45 volumiobe volumio[961]: info: Apr 13 10:39:45 volumiobe volumio[961]: ---------------------------- MPD announces state update: player Apr 13 10:39:45 volumiobe volumio[961]: info: ControllerMpd::getState Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:39:45 volumiobe volumio[961]: info: Apr 13 10:39:45 volumiobe volumio[961]: ---------------------------- MPD announces state update: player Apr 13 10:39:45 volumiobe volumio[961]: info: sendMpdCommand status took 21 milliseconds Apr 13 10:39:45 volumiobe volumio[961]: info: ControllerMpd::getState Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::parseState Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:39:45 volumiobe volumio[961]: info: sendMpdCommand status took 3 milliseconds Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::parseState Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:39:45 volumiobe volumio[961]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 13 10:39:45 volumiobe volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:39:45 volumiobe volumio[961]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:39:45 volumiobe volumio[961]: info: ControllerMpd::pushState Apr 13 10:39:45 volumiobe volumio[961]: info: CoreCommandRouter::servicePushState Apr 13 10:39:45 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:45 volumiobe volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"1:0:2:4347:300C:13E:820000:0:0:0:","artist":null,"album":null,"uri":"http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:","trackType":""} Apr 13 10:39:45 volumiobe volumio[961]: verbose: CURRENT POSITION 0 Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::syncState stateService play Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::syncState currentStatus stop Apr 13 10:39:45 volumiobe volumio[961]: info: ControllerMpd::pushState Apr 13 10:39:45 volumiobe volumio[961]: info: CoreCommandRouter::servicePushState Apr 13 10:39:45 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:45 volumiobe volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":839,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"1:0:2:4347:300C:13E:820000:0:0:0:","artist":null,"album":null,"uri":"http://172.22.16.220:8001/1:0:2:4347:300C:13E:820000:0:0:0:","trackType":""} Apr 13 10:39:45 volumiobe volumio[961]: verbose: CURRENT POSITION 0 Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::syncState stateService play Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:39:45 volumiobe volumio[961]: info: Received an update from plugin. extracting info from payload Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::pushState Apr 13 10:39:45 volumiobe volumio[961]: info: CorePlayQueue::getTrack 0 Apr 13 10:39:45 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:39:45 volumiobe volumio[961]: info: CoreCommandRouter::volumioPushState Apr 13 10:39:45 volumiobe volumio[961]: info: CoreStateMachine::pushState Apr 13 10:39:45 volumiobe volumio[961]: info: CoreCommandRouter::volumioPushState Apr 13 10:39:45 volumiobe volumio[961]: info: ------------------------------ 51ms Apr 13 10:39:45 volumiobe volumio[961]: info: ------------------------------ 37ms Apr 13 10:39:45 volumiobe volumio[961]: info: [ASDebug] CurState: play PrevState: stop Apr 13 10:39:45 volumiobe volumio[961]: info: [ASDebug] Togle GPIO: ON Apr 13 10:39:45 volumiobe volumio[961]: info: [ASDebug] CurState: play PrevState: play Apr 13 10:39:46 volumiobe kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Apr 13 10:39:47 volumiobe volumio[961]: info: CoreCommandRouter::volumioGetState Apr 13 10:39:49 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 10:39:49 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 10:39:58 volumiobe volumio[961]: info: Enabling plugin spop Apr 13 10:39:58 volumiobe volumio[961]: info: Loading plugin "spop"... Apr 13 10:39:58 volumiobe volumio[961]: info: PLUGIN START: spop Apr 13 10:39:58 volumiobe volumio[961]: info: Checking for old spotify connect plugin installed Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 10:39:58 volumiobe volumio[961]: [SpotifyConnect] Creating VLS config file Apr 13 10:39:58 volumiobe volumio[961]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 10:39:58 volumiobe volumio[961]: [SpotifyConnect] Starting metadata listener Apr 13 10:39:58 volumiobe volumio[961]: (node:961) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Apr 13 10:39:58 volumiobe volumio[961]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2607:27) Apr 13 10:39:58 volumiobe volumio[961]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2470:14) Apr 13 10:39:58 volumiobe volumio[961]: at Promise._successFn (/data/plugins/music_service/spop/index.js:134:14) Apr 13 10:39:58 volumiobe volumio[961]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Apr 13 10:39:58 volumiobe volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 13 10:39:58 volumiobe volumio[961]: (node:961) 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) Apr 13 10:39:58 volumiobe sudo[6869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 13 10:39:58 volumiobe sudo[6869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 10:39:58 volumiobe volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 13 10:39:58 volumiobe systemd[1]: Started Volspotconnect2 Daemon. Apr 13 10:39:58 volumiobe volumio[6871]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Apr 13 10:39:58 volumiobe sudo[6869]: pam_unix(sudo:session): session closed for user root Apr 13 10:39:58 volumiobe systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Apr 13 10:39:58 volumiobe systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 13 10:39:58 volumiobe volumio[961]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 13 10:39:58 volumiobe volumio[961]: [Metrics] SpotifyConnect: 0s 163.17ms Apr 13 10:39:58 volumiobe volumio[961]: info: Done. Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 13 10:39:58 volumiobe volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 10:39:58 volumiobe volumio[961]: [SpotifyConnect] Closing metadata listener Apr 13 10:39:58 volumiobe volumio[961]: info: Disabling plugin spop Apr 13 10:39:58 volumiobe volumio[961]: info: Done. Apr 13 10:39:58 volumiobe sudo[6875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Apr 13 10:39:58 volumiobe sudo[6875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 10:39:58 volumiobe systemd[1]: Stopped Volspotconnect2 Daemon. Apr 13 10:39:58 volumiobe sudo[6875]: pam_unix(sudo:session): session closed for user root Apr 13 10:39:58 volumiobe volumio[961]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 13 10:40:02 volumiobe volumio[961]: info: Checking Spotify Web API Apr 13 10:40:02 volumiobe volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 10:40:02 volumiobe volumio[961]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 13 10:40:02 volumiobe volumio[961]: at healthCheck (dgram.js:897:11) Apr 13 10:40:02 volumiobe volumio[961]: at Socket.send (dgram.js:622:3) Apr 13 10:40:02 volumiobe volumio[961]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Apr 13 10:40:02 volumiobe volumio[961]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Apr 13 10:40:02 volumiobe volumio[961]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2475:18) Apr 13 10:40:02 volumiobe volumio[961]: at listOnTimeout (internal/timers.js:554:17) Apr 13 10:40:02 volumiobe volumio[961]: at processTimers (internal/timers.js:497:7) { Apr 13 10:40:02 volumiobe volumio[961]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Apr 13 10:40:02 volumiobe volumio[961]: } Apr 13 10:40:02 volumiobe volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 10:40:02 volumiobe sudo[6886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-13 10:39 Apr 13 10:40:02 volumiobe sudo[6886]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"