-- Logs begin at Tue 2025-04-15 21:17:02 UTC, end at Fri 2025-04-18 05:44:08 UTC. -- Apr 18 05:43:00 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 05:43:01 mania1-music sudo[4805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:43:01 mania1-music sudo[4805]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:43:01 mania1-music sudo[4805]: pam_unix(sudo:session): session closed for user root Apr 18 05:43:01 mania1-music sudo[4813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:43:01 mania1-music sudo[4813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:43:01 mania1-music sudo[4813]: pam_unix(sudo:session): session closed for user root Apr 18 05:43:01 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:43:01 mania1-music volumio[914]: info: CoreCommandRouter::volumioGetState Apr 18 05:43:01 mania1-music volumio[914]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:43:01 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: info: Retrieving Cloud Streaming UI Apr 18 05:43:03 mania1-music volumio[914]: info: Getting Tidal Cloud Configuration Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: info: Getting Qobuz Cloud Configuration Apr 18 05:43:03 mania1-music volumio[914]: info: Asking plugin for UI Config Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: info: Getting Spotify Cloud Configuration Apr 18 05:43:03 mania1-music volumio[914]: info: Asking plugin for UI Config Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: info: Saving Spotify Acccount Apr 18 05:43:03 mania1-music volumio[914]: info: Got Tidal Cloud Configuration Apr 18 05:43:03 mania1-music volumio[914]: info: Got it Apr 18 05:43:03 mania1-music volumio[914]: info: Got it Apr 18 05:43:03 mania1-music volumio[914]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:43:03 mania1-music volumio[914]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 05:43:03 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 18 05:43:07 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 05:43:11 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 05:43:15 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 05:43:19 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 05:43:24 mania1-music volumio[914]: info: Following Redirect to: http://plugins.volumio.workers.dev/plugins/volumio/armhf/plugins.json Apr 18 05:43:29 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 18 05:43:29 mania1-music volumio[914]: info: CoreCommandRouter::volumioGetQueue Apr 18 05:43:29 mania1-music volumio[914]: info: CoreStateMachine::getQueue Apr 18 05:43:29 mania1-music volumio[914]: info: CorePlayQueue::getQueue Apr 18 05:43:29 mania1-music volumio[914]: info: Listing playlists Apr 18 05:43:32 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 05:43:32 mania1-music volumio[914]: info: CURURI: music-library Apr 18 05:43:32 mania1-music volumio[914]: error: Failed LSINFO: null Apr 18 05:43:36 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 05:43:36 mania1-music volumio[914]: info: CURURI: playlists Apr 18 05:43:36 mania1-music volumio[914]: info: Listing playlists Apr 18 05:43:56 mania1-music volumio[914]: info: Apr 18 05:43:56 mania1-music volumio[914]: ---------------------------- MPD announces system playlist update Apr 18 05:43:56 mania1-music volumio[914]: info: Ignoring MPD Status Update Apr 18 05:43:56 mania1-music volumio[914]: info: Apr 18 05:43:56 mania1-music volumio[914]: ---------------------------- MPD announces state update: player Apr 18 05:43:56 mania1-music volumio[914]: info: ControllerMpd::getState Apr 18 05:43:56 mania1-music volumio[914]: verbose: ControllerMpd::sendMpdCommand status Apr 18 05:43:56 mania1-music volumio[914]: info: ------------------------------ 3ms Apr 18 05:43:56 mania1-music volumio[914]: info: sendMpdCommand status took 2 milliseconds Apr 18 05:43:56 mania1-music volumio[914]: verbose: ControllerMpd::parseState Apr 18 05:43:56 mania1-music volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 05:43:56 mania1-music volumio[914]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 05:43:56 mania1-music volumio[914]: verbose: ControllerMpd::parseTrackInfo Apr 18 05:43:56 mania1-music volumio[914]: info: ControllerMpd::pushState Apr 18 05:43:56 mania1-music volumio[914]: info: CoreCommandRouter::servicePushState Apr 18 05:43:56 mania1-music volumio[914]: info: CorePlayQueue::getTrack 0 Apr 18 05:43:56 mania1-music volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26776311,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gorje Hewek, Etne - Children (Extended Mix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Apr 18 05:43:56 mania1-music volumio[914]: verbose: CURRENT POSITION 0 Apr 18 05:43:56 mania1-music volumio[914]: info: CoreStateMachine::syncState stateService play Apr 18 05:43:56 mania1-music volumio[914]: info: CoreStateMachine::syncState currentStatus play Apr 18 05:43:56 mania1-music volumio[914]: info: Received an update from plugin. extracting info from payload Apr 18 05:43:56 mania1-music volumio[914]: info: CoreStateMachine::pushState Apr 18 05:43:56 mania1-music volumio[914]: info: CoreCommandRouter::volumioPushState Apr 18 05:43:56 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:43:56 mania1-music volumio[914]: info: CoreStateMachine::pushState Apr 18 05:43:56 mania1-music volumio[914]: info: CoreCommandRouter::volumioPushState Apr 18 05:43:56 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:43:56 mania1-music volumio[914]: info: ------------------------------ 18ms Apr 18 05:43:59 mania1-music volumio[914]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 18 05:43:59 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:43:59 mania1-music volumio[914]: [SpotifyConnect] Closing metadata listener Apr 18 05:43:59 mania1-music volumio[914]: info: Disabling plugin spop Apr 18 05:43:59 mania1-music volumio[914]: info: Done. Apr 18 05:43:59 mania1-music sudo[4959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Apr 18 05:43:59 mania1-music sudo[4959]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:43:59 mania1-music systemd[1]: Stopping Volspotconnect2 Daemon... Apr 18 05:43:59 mania1-music systemd[1]: Stopped Volspotconnect2 Daemon. Apr 18 05:43:59 mania1-music sudo[4959]: pam_unix(sudo:session): session closed for user root Apr 18 05:43:59 mania1-music volumio[914]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 18 05:44:02 mania1-music volumio[914]: info: Enabling plugin spop Apr 18 05:44:02 mania1-music volumio[914]: info: Loading plugin "spop"... Apr 18 05:44:02 mania1-music volumio[914]: info: PLUGIN START: spop Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:44:02 mania1-music volumio[914]: [SpotifyConnect] Creating VLS config file Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:44:02 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:44:02 mania1-music sudo[4992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 18 05:44:02 mania1-music volumio[914]: [SpotifyConnect] Starting metadata listener Apr 18 05:44:02 mania1-music sudo[4992]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:44:02 mania1-music sudo[4992]: pam_unix(sudo:session): session closed for user root Apr 18 05:44:02 mania1-music volumio[914]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Apr 18 05:44:02 mania1-music volumio[914]: at Object._errnoException (util.js:1022:11) Apr 18 05:44:02 mania1-music volumio[914]: at _exceptionWithHostPort (util.js:1044:20) Apr 18 05:44:02 mania1-music volumio[914]: at _handle.lookup (dgram.js:266:18) Apr 18 05:44:02 mania1-music volumio[914]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Apr 18 05:44:02 mania1-music volumio[914]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 18 05:44:02 mania1-music volumio[914]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Apr 18 05:44:02 mania1-music volumio[914]: at Socket.onListenError (dgram.js:362:22) Apr 18 05:44:02 mania1-music volumio[914]: at Object.onceWrapper (events.js:315:30) Apr 18 05:44:02 mania1-music volumio[914]: at emitOne (events.js:121:20) Apr 18 05:44:02 mania1-music volumio[914]: at Socket.emit (events.js:211:7) Apr 18 05:44:02 mania1-music volumio[914]: at _handle.lookup (dgram.js:267:14) Apr 18 05:44:02 mania1-music volumio[914]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Apr 18 05:44:02 mania1-music volumio[914]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 18 05:44:02 mania1-music sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 18 05:44:02 mania1-music sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:44:02 mania1-music systemd[1]: Starting Volspotconnect2 Daemon... Apr 18 05:44:02 mania1-music systemd[1]: Started Volspotconnect2 Daemon. Apr 18 05:44:02 mania1-music sudo[5001]: pam_unix(sudo:session): session closed for user root Apr 18 05:44:02 mania1-music volumio[5007]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 18 05:44:02 mania1-music volumio[5007]: Reading Config from "/tmp/volspotify.toml" Apr 18 05:44:02 mania1-music volumio[5007]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Apr 18 05:44:02 mania1-music volumio[914]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 18 05:44:02 mania1-music volumio[914]: [Metrics] SpotifyConnect: 0s 105.74ms Apr 18 05:44:02 mania1-music volumio[914]: info: Done. Apr 18 05:44:02 mania1-music volumio[5007]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 18 05:44:02 mania1-music volumio[5007]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111) Apr 18 05:44:04 mania1-music volumio[914]: info: Starting Uninstall of plugin music_service - spop Apr 18 05:44:04 mania1-music volumio[914]: info: Uninstalling plugin spop Apr 18 05:44:04 mania1-music volumio[914]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 18 05:44:04 mania1-music volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:44:04 mania1-music volumio[914]: [SpotifyConnect] Closing metadata listener Apr 18 05:44:04 mania1-music volumio[914]: info: Disabling plugin spop Apr 18 05:44:04 mania1-music volumio[914]: info: Checking if uninstall.sh is present Apr 18 05:44:04 mania1-music volumio[914]: info: Executing uninstall.sh Apr 18 05:44:04 mania1-music sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Apr 18 05:44:04 mania1-music sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:44:04 mania1-music systemd[1]: Stopping Volspotconnect2 Daemon... Apr 18 05:44:04 mania1-music systemd[1]: Stopped Volspotconnect2 Daemon. Apr 18 05:44:04 mania1-music sudo[5021]: pam_unix(sudo:session): session closed for user root Apr 18 05:44:04 mania1-music volumio[914]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 18 05:44:04 mania1-music sudo[5028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Apr 18 05:44:05 mania1-music sudo[5028]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:44:05 mania1-music sudo[5028]: pam_unix(sudo:session): session closed for user root Apr 18 05:44:05 mania1-music volumio[914]: info: Uninstall script completed Apr 18 05:44:05 mania1-music volumio[914]: info: Removing plugin spop from configuration Apr 18 05:44:05 mania1-music volumio[914]: info: Successfully removed spop configuration files Apr 18 05:44:05 mania1-music volumio[914]: info: Plugin folders cleanup Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category audio_interface Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category miscellanea Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category music_service Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category plugins.json Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category system_controller Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category user_interface Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning into folder /data/plugins/ Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category miscellanea Apr 18 05:44:05 mania1-music volumio[914]: info: Scanning category music_service Apr 18 05:44:05 mania1-music volumio[914]: info: Cleaning folder for spop Apr 18 05:44:05 mania1-music kernel: rpi_firmware_get_throttled: 4 callbacks suppressed Apr 18 05:44:05 mania1-music kernel: Under-voltage detected! (0x00050005) Apr 18 05:44:06 mania1-music volumio[914]: info: Plugin folders cleanup completed Apr 18 05:44:06 mania1-music volumio[914]: info: [{"prettyName":"AutoStart","name":"autostart","category":"miscellanea","version":"1.1.2","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"Squeezelite player","name":"squeezelite","category":"music_service","version":"1.1.1","icon":"fa fa-music","enabled":true,"active":true},{"prettyName":"Volumio Spotify Connect2","name":"volspotconnect2","category":"music_service","version":"1.0.5","icon":"fa-spotify","enabled":true,"active":true}] Apr 18 05:44:06 mania1-music volumio[914]: info: Checking Spotify Web API Apr 18 05:44:06 mania1-music volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:44:06 mania1-music volumio[914]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 18 05:44:06 mania1-music volumio[914]: at Socket._healthCheck (dgram.js:638:11) Apr 18 05:44:06 mania1-music volumio[914]: at Socket.send (dgram.js:435:8) Apr 18 05:44:06 mania1-music volumio[914]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Apr 18 05:44:06 mania1-music volumio[914]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) Apr 18 05:44:06 mania1-music volumio[914]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2410:18) Apr 18 05:44:06 mania1-music volumio[914]: at ontimeout (timers.js:482:11) Apr 18 05:44:06 mania1-music volumio[914]: at tryOnTimeout (timers.js:317:5) Apr 18 05:44:06 mania1-music volumio[914]: at Timer.listOnTimeout (timers.js:277:5) Apr 18 05:44:06 mania1-music volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:44:08 mania1-music sudo[5056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:43 Apr 18 05:44:08 mania1-music sudo[5056]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"