-- Logs begin at Thu 2025-10-23 13:20:50 UTC, end at Thu 2025-10-23 13:29:13 UTC. -- Oct 23 13:28:04 volumio volumio[4803]: info: CALLMETHOD: music_service volspotconnect2 saveVolspotconnectAccount [object Object] Oct 23 13:28:04 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: volspotconnect2 , saveVolspotconnectAccount Oct 23 13:28:04 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:28:04 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:04 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:04 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:28:04 volumio sudo[6542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 23 13:28:04 volumio sudo[6542]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:04 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:28:04 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:28:04 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:28:04 volumio sudo[6542]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:04 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:28:04 volumio volumio[6545]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 23 13:28:04 volumio volumio[6545]: Reading Config from "volspotify.toml" Oct 23 13:28:04 volumio volumio[6545]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Oct 23 13:28:04 volumio volumio[6545]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 23 13:28:04 volumio volumio[6545]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 23 13:28:24 volumio volumio[4803]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 23 13:28:24 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 13:28:24 volumio volumio[4803]: [SpotifyConnect] Closing metadata listener Oct 23 13:28:24 volumio volumio[4803]: info: Disabling plugin spop Oct 23 13:28:24 volumio volumio[4803]: info: Done. Oct 23 13:28:24 volumio sudo[6607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Oct 23 13:28:24 volumio sudo[6607]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:24 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:28:24 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 23 13:28:24 volumio sudo[6607]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:24 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service stoped! Oct 23 13:28:25 volumio volumio[4803]: info: Enabling plugin spop Oct 23 13:28:25 volumio volumio[4803]: info: Loading plugin "spop"... Oct 23 13:28:25 volumio volumio[4803]: info: PLUGIN START: spop Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 23 13:28:25 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:25 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:25 volumio volumio[4803]: [SpotifyConnect] Starting metadata listener Oct 23 13:28:25 volumio sudo[6615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 23 13:28:25 volumio sudo[6615]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:25 volumio sudo[6615]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:25 volumio sudo[6618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 23 13:28:25 volumio sudo[6618]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:25 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:28:25 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:28:25 volumio sudo[6618]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:25 volumio volumio[6620]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 23 13:28:25 volumio volumio[6620]: Reading Config from "/tmp/volspotify.toml" Oct 23 13:28:25 volumio kernel: vollibrespot[6621]: syscall 397 Oct 23 13:28:25 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 23 13:28:25 volumio volumio[4803]: [Metrics] SpotifyConnect: 0s 77.82ms Oct 23 13:28:25 volumio volumio[4803]: info: Done. Oct 23 13:28:25 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 23 13:28:25 volumio kernel: CPU: 3 PID: 6621 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 23 13:28:25 volumio kernel: task: ffffffc012eee000 ti: ffffffc024a80000 task.ti: ffffffc024a80000 Oct 23 13:28:25 volumio kernel: PC is at 0xf7420a42 Oct 23 13:28:25 volumio kernel: LR is at 0xab5ecc60 Oct 23 13:28:25 volumio kernel: pc : [<00000000f7420a42>] lr : [<00000000ab5ecc60>] pstate: 60010030 Oct 23 13:28:25 volumio kernel: sp : 00000000ff8365c8 Oct 23 13:28:25 volumio kernel: x12: 00000000ff8365d8 Oct 23 13:28:25 volumio kernel: x11: 00000000ff836798 x10: 00000000ab615bac Oct 23 13:28:25 volumio kernel: x9 : 00000000ff837030 x8 : 0000000000001000 Oct 23 13:28:25 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 23 13:28:25 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 23 13:28:25 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 23 13:28:25 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 23 13:28:25 volumio kernel: Oct 23 13:28:25 volumio volumio[6620]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Oct 23 13:28:25 volumio volumio[6620]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Stopping Vollibrespot daemon Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Closing metadata listener Oct 23 13:28:26 volumio volumio[4803]: info: Disabling plugin volspotconnect2 Oct 23 13:28:26 volumio volumio[4803]: info: Done. Oct 23 13:28:26 volumio sudo[6629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service Oct 23 13:28:26 volumio sudo[6629]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:26 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:28:26 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 23 13:28:26 volumio sudo[6629]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service stoped! Oct 23 13:28:26 volumio volumio[4803]: info: Enabling plugin volspotconnect2 Oct 23 13:28:26 volumio volumio[4803]: info: Loading plugin "volspotconnect2"... Oct 23 13:28:26 volumio volumio[4803]: info: PLUGIN START: volspotconnect2 Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:28:26 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:26 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Starting metadata listener Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Oct 23 13:28:26 volumio volumio[4803]: at Object._errnoException (util.js:1022:11) Oct 23 13:28:26 volumio volumio[4803]: at _exceptionWithHostPort (util.js:1044:20) Oct 23 13:28:26 volumio volumio[4803]: at _handle.lookup (dgram.js:266:18) Oct 23 13:28:26 volumio volumio[4803]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Oct 23 13:28:26 volumio volumio[4803]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Oct 23 13:28:26 volumio volumio[4803]: at Socket.onListenError (dgram.js:362:22) Oct 23 13:28:26 volumio volumio[4803]: at Object.onceWrapper (events.js:315:30) Oct 23 13:28:26 volumio volumio[4803]: at emitOne (events.js:121:20) Oct 23 13:28:26 volumio volumio[4803]: at Socket.emit (events.js:211:7) Oct 23 13:28:26 volumio volumio[4803]: at _handle.lookup (dgram.js:267:14) Oct 23 13:28:26 volumio volumio[4803]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Oct 23 13:28:26 volumio volumio[4803]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 23 13:28:26 volumio sudo[6638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 23 13:28:26 volumio sudo[6638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:28:26 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:28:26 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:28:26 volumio sudo[6638]: pam_unix(sudo:session): session closed for user root Oct 23 13:28:26 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 23 13:28:26 volumio volumio[4803]: [Metrics] SpotifyConnect: 0s 56.40ms Oct 23 13:28:26 volumio volumio[4803]: info: Done. Oct 23 13:28:26 volumio volumio[6640]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 23 13:28:26 volumio volumio[6640]: Reading Config from "volspotify.toml" Oct 23 13:28:26 volumio volumio[6640]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Oct 23 13:28:26 volumio volumio[6640]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 23 13:28:27 volumio volumio[6640]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 23 13:28:29 volumio volumio[4803]: info: Checking Spotify Web API Oct 23 13:28:36 volumio volumio[6640]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 13:29:01 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 13:29:06 volumio volumio[4803]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 13:29:06 volumio volumio[4803]: info: Output device has changed, restarting MPD Oct 23 13:29:06 volumio sudo[6798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 13:29:06 volumio sudo[6798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:06 volumio volumio[4803]: info: Output device has changed, restarting Shairport Sync Oct 23 13:29:06 volumio sudo[6798]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:06 volumio sudo[6801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 13:29:06 volumio sudo[6801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:06 volumio systemd[1]: Stopping Music Player Daemon... Oct 23 13:29:06 volumio volumio[4803]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 13:29:06 volumio volumio[4803]: info: Setting mixer Speaker for card USB Sound Device Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: [SpotifyConnect] Creating VLS config file Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:06 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio systemd[1]: Starting Music Player Daemon... Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 23 13:29:07 volumio volumio[4803]: info: Updating Volume Controller Parameters: Device: 1 Name: USB Sound Device Mixer: Speaker Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio sudo[6818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 23 13:29:07 volumio sudo[6818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio sudo[6819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 23 13:29:07 volumio sudo[6819]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:07 volumio sudo[6824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 23 13:29:07 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:07 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:07 volumio sudo[6818]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio sudo[6824]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio sudo[6828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 23 13:29:07 volumio sudo[6828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio volumio[4803]: info: MPD Permissions set Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:07 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:07 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:07 volumio sudo[6819]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio sudo[6824]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio volumio[6834]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 23 13:29:07 volumio volumio[6834]: Reading Config from "/tmp/volspotify.toml" Oct 23 13:29:07 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:07 volumio kernel: vollibrespot[6836]: syscall 397 Oct 23 13:29:07 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 23 13:29:07 volumio kernel: CPU: 1 PID: 6836 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 23 13:29:07 volumio kernel: task: ffffffc0152a0000 ti: ffffffc024e1c000 task.ti: ffffffc024e1c000 Oct 23 13:29:07 volumio kernel: PC is at 0xf7153a42 Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 13:29:07 volumio kernel: LR is at 0xab25dc60 Oct 23 13:29:07 volumio kernel: pc : [<00000000f7153a42>] lr : [<00000000ab25dc60>] pstate: 60010030 Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 13:29:07 volumio kernel: sp : 00000000ffc34e18 Oct 23 13:29:07 volumio kernel: x12: 00000000ffc34e28 Oct 23 13:29:07 volumio kernel: x11: 00000000ffc34fe8 x10: 00000000ab286bac Oct 23 13:29:07 volumio kernel: x9 : 00000000ffc35880 x8 : 0000000000001000 Oct 23 13:29:07 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 23 13:29:07 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 23 13:29:07 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 23 13:29:07 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 23 13:29:07 volumio kernel: Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 13:29:07 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 13:29:07 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:07 volumio sudo[6828]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio volumio[6840]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 23 13:29:07 volumio volumio[6840]: Reading Config from "/tmp/volspotify.toml" Oct 23 13:29:07 volumio kernel: vollibrespot[6841]: syscall 397 Oct 23 13:29:07 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 23 13:29:07 volumio kernel: CPU: 1 PID: 6841 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 23 13:29:07 volumio kernel: task: ffffffc012c20000 ti: ffffffc024e24000 task.ti: ffffffc024e24000 Oct 23 13:29:07 volumio kernel: PC is at 0xf6f96a42 Oct 23 13:29:07 volumio kernel: LR is at 0xab2bec60 Oct 23 13:29:07 volumio kernel: pc : [<00000000f6f96a42>] lr : [<00000000ab2bec60>] pstate: 60010030 Oct 23 13:29:07 volumio kernel: sp : 00000000ff9d8648 Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 13:29:07 volumio kernel: x12: 00000000ff9d8658 Oct 23 13:29:07 volumio kernel: x11: 00000000ff9d8818 x10: 00000000ab2e7bac Oct 23 13:29:07 volumio kernel: x9 : 00000000ff9d90b0 x8 : 0000000000001000 Oct 23 13:29:07 volumio volumio[4803]: error: Upnp client error: Error: This socket has been ended by the other party Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio volumio[4803]: info: VolumeController:: Volume=29 Mute =false Oct 23 13:29:07 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 23 13:29:07 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 23 13:29:07 volumio volumio[4803]: info: CoreStateMachine::pushState Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::volumioPushState Oct 23 13:29:07 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 23 13:29:07 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 23 13:29:07 volumio kernel: Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio volumio[4803]: info: Starting Shairport Sync Oct 23 13:29:07 volumio volumio[6840]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio sudo[6853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 13:29:07 volumio sudo[6853]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio volumio[6840]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 23 13:29:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 23 13:29:07 volumio sudo[6855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 23 13:29:07 volumio sudo[6855]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio sudo[6858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 23 13:29:07 volumio sudo[6858]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 23 13:29:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 23 13:29:07 volumio sudo[6853]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio sudo[6861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 23 13:29:07 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:07 volumio sudo[6861]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio sudo[6866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 23 13:29:07 volumio sudo[6866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 13:29:07 volumio volumio[4803]: info: Shairport-Sync Started Oct 23 13:29:07 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:07 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:07 volumio sudo[6855]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio sudo[6861]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio sudo[6858]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio sudo[6866]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio volumio[6879]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 23 13:29:07 volumio volumio[6879]: Reading Config from "volspotify.toml" Oct 23 13:29:07 volumio volumio[6879]: Malformed config key: expected an equals, found a newline at line 33 column 6 Oct 23 13:29:07 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Oct 23 13:29:07 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio volumio[4803]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 23 13:29:07 volumio systemd[1]: Started Music Player Daemon. Oct 23 13:29:07 volumio sudo[6801]: pam_unix(sudo:session): session closed for user root Oct 23 13:29:07 volumio volumio[4803]: error: updateQueue error: null Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 23 13:29:09 volumio volumio[4803]: info: CoreStateMachine::getcurrentVolume Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 13:29:09 volumio volumio[4803]: info: VolumeController:: Volume=29 Mute =false Oct 23 13:29:09 volumio volumio[4803]: info: CoreStateMachine::pushState Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::volumioPushState Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 13:29:09 volumio volumio[4803]: info: CoreStateMachine::updateTrackBlock Oct 23 13:29:09 volumio volumio[4803]: info: CorePlayQueue::getTrackBlock Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 13:29:09 volumio volumio[4803]: info: VolumeController:: Volume=29 Mute =false Oct 23 13:29:09 volumio volumio[4803]: info: CoreStateMachine::pushState Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::volumioPushState Oct 23 13:29:09 volumio volumio[4803]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 13:29:09 volumio systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 23 13:29:09 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:09 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:09 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:09 volumio volumio[6892]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 23 13:29:09 volumio volumio[6892]: Reading Config from "volspotify.toml" Oct 23 13:29:09 volumio volumio[6892]: Malformed config key: expected an equals, found a newline at line 33 column 6 Oct 23 13:29:09 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Oct 23 13:29:09 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Oct 23 13:29:11 volumio systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 23 13:29:11 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 23 13:29:11 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 23 13:29:12 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 23 13:29:12 volumio volumio[6896]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 23 13:29:12 volumio volumio[6896]: Reading Config from "volspotify.toml" Oct 23 13:29:12 volumio volumio[6896]: Malformed config key: expected an equals, found a newline at line 33 column 6 Oct 23 13:29:12 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Oct 23 13:29:12 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Oct 23 13:29:13 volumio volumio[4803]: info: Checking Spotify Web API Oct 23 13:29:13 volumio volumio[4803]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 13:29:13 volumio volumio[4803]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 23 13:29:13 volumio volumio[4803]: at Socket._healthCheck (dgram.js:638:11) Oct 23 13:29:13 volumio volumio[4803]: at Socket.send (dgram.js:435:8) Oct 23 13:29:13 volumio volumio[4803]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Oct 23 13:29:13 volumio volumio[4803]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) Oct 23 13:29:13 volumio volumio[4803]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2651:18) Oct 23 13:29:13 volumio volumio[4803]: at ontimeout (timers.js:482:11) Oct 23 13:29:13 volumio volumio[4803]: at tryOnTimeout (timers.js:317:5) Oct 23 13:29:13 volumio volumio[4803]: at Timer.listOnTimeout (timers.js:277:5) Oct 23 13:29:13 volumio volumio[4803]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 13:29:13 volumio sudo[6909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-23 13:28 Oct 23 13:29:13 volumio sudo[6909]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="493b3f2063c664b52681092d76718ef5fb070910" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="d9435b7a968fdb32f8774ce87d86f5f44a90fb66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun Aug 2 23:36:23 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="721ce12033fe994c37bd358dd68cf9f9"