-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2025-05-30 18:38:29 UTC. -- May 30 18:37:00 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioGetState May 30 18:37:00 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:02 volumio-czarny kernel: hwmon hwmon1: Voltage normalised May 30 18:37:02 volumio-czarny volumio[1013]: info: Executing endpoint metavolumio May 30 18:37:02 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 18:37:02 volumio-czarny volumio[1013]: info: Executing endpoint metavolumio May 30 18:37:02 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 18:37:02 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 18:37:03 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 18:37:03 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 30 18:37:04 volumio-czarny volumio[1013]: error: Failed request for metavolumio API May 30 18:37:04 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:37:10 volumio-czarny volumio[1013]: info: Enabling plugin peppy_screensaver May 30 18:37:10 volumio-czarny volumio[1013]: info: Loading plugin "peppy_screensaver"... May 30 18:37:10 volumio-czarny volumio[1013]: info: Applying required configuration parameters for plugin peppy_screensaver May 30 18:37:10 volumio-czarny volumio[1013]: info: Preparing to generate the ALSA configuration file May 30 18:37:10 volumio-czarny volumio[1013]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 30 18:37:10 volumio-czarny volumio[1013]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 30 18:37:10 volumio-czarny volumio[1013]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 18:37:10 volumio-czarny volumio[1013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 30 18:37:10 volumio-czarny volumio[1013]: info: Reading ALSA contributions from plugins. May 30 18:37:10 volumio-czarny volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 30 18:37:10 volumio-czarny volumio[1013]: info: Asound.conf file written May 30 18:37:10 volumio-czarny sudo[5726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 30 18:37:10 volumio-czarny sudo[5726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:10 volumio-czarny sudo[5726]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: info: Output device has changed, restarting MPD May 30 18:37:11 volumio-czarny sudo[5732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 18:37:11 volumio-czarny sudo[5732]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny sudo[5732]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: info: Output device has changed, restarting Shairport Sync May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:11 volumio-czarny sudo[5735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 18:37:11 volumio-czarny sudo[5735]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny systemd[1]: Stopping Music Player Daemon... May 30 18:37:11 volumio-czarny volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 18:37:11 volumio-czarny volumio[1013]: [SpotifyConnect] Creating VLS config file May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 30 18:37:11 volumio-czarny systemd[1]: mpd.service: Succeeded. May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny systemd[1]: Stopped Music Player Daemon. May 30 18:37:11 volumio-czarny vtcs[4085]: [2025-05-30 18:37:11.184] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 30 18:37:11 volumio-czarny vtcs[4085]: [2025-05-30 18:37:11.186] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 30 18:37:11 volumio-czarny vtcs[4085]: [2025-05-30 18:37:11.186] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 30 18:37:11 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:11 volumio-czarny volumio[1013]: info: PLUGIN START: peppy_screensaver May 30 18:37:11 volumio-czarny volumio[1013]: info: Loading i18n strings for locale pl May 30 18:37:11 volumio-czarny sudo[5745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:11 volumio-czarny sudo[5745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny systemd[1]: Stopping Volumio Tidal Connect Service... May 30 18:37:11 volumio-czarny systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 30 18:37:11 volumio-czarny systemd[1]: vtcs.service: Succeeded. May 30 18:37:11 volumio-czarny systemd[1]: Stopped Volumio Tidal Connect Service. May 30 18:37:11 volumio-czarny sudo[5745]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 30 18:37:11 volumio-czarny sudo[5740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:11 volumio-czarny sudo[5740]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny sudo[5740]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: info: /tmp/myfifo created May 30 18:37:11 volumio-czarny sudo[5754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 May 30 18:37:11 volumio-czarny sudo[5754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny volumio[1013]: info: snd-dummy loaded May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:11 volumio-czarny sudo[5754]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 30 18:37:11 volumio-czarny volumio[1013]: info: Preparing to generate the ALSA configuration file May 30 18:37:11 volumio-czarny volumio[1013]: info: Done. May 30 18:37:11 volumio-czarny volumio[1013]: [SpotifyConnect] Restarting Vollibrespot Daemon May 30 18:37:11 volumio-czarny sudo[5764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 30 18:37:11 volumio-czarny sudo[5764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny volumio[1013]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 30 18:37:11 volumio-czarny volumio[1013]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 30 18:37:11 volumio-czarny volumio[1013]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 18:37:11 volumio-czarny volumio[1013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 30 18:37:11 volumio-czarny volumio[1013]: info: Reading ALSA contributions from plugins. May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 18:37:11 volumio-czarny volumio[1013]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 30 18:37:11 volumio-czarny systemd[1]: Stopping Volspotconnect2 Daemon... May 30 18:37:11 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM May 30 18:37:11 volumio-czarny systemd[1]: volspotconnect.service: Succeeded. May 30 18:37:11 volumio-czarny volumio[1013]: info: Received Get System Version May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 18:37:11 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:37:11 volumio-czarny volumio[1013]: info: Received Get System Info May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 18:37:11 volumio-czarny volumio[1013]: info: Discovery: Getting this device information May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioGetState May 30 18:37:11 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:37:11 volumio-czarny sudo[5767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:11 volumio-czarny sudo[5767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:11 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:11 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:11 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:37:11 volumio-czarny sudo[5764]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:11 volumio-czarny volumio[5768]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 30 18:37:11 volumio-czarny volumio[5768]: Reading Config from "/tmp/volspotify.toml" May 30 18:37:11 volumio-czarny volumio[5768]: [Vollibrespot] : Using Alsa backend with device: volumio May 30 18:37:11 volumio-czarny sudo[5767]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 30 18:37:11 volumio-czarny volumio[1013]: info: MPD Permissions set May 30 18:37:11 volumio-czarny volumio[5768]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioGetState May 30 18:37:11 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:11 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 30 18:37:11 volumio-czarny volumio[5768]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 30 18:37:11 volumio-czarny volumio[1013]: info: camilladsp spawned new process with pid 5777, instance 1, run: true May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:11 volumio-czarny sudo[5788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /data/configuration/miscellanea/peppy_screensaver/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 30 18:37:11 volumio-czarny sudo[5788]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:11 volumio-czarny sudo[5788]: pam_unix(sudo:session): session closed for user root May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:11 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioGetState May 30 18:37:12 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 30 18:37:12 volumio-czarny sudo[5800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 18:37:12 volumio-czarny sudo[5800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5800]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny volumio[1013]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 30 18:37:12 volumio-czarny volumio[1013]: info: Starting Shairport Sync May 30 18:37:12 volumio-czarny volumio[1013]: info: touch_display: Setting screensaver timeout to 0 seconds. May 30 18:37:12 volumio-czarny volumio[1013]: info: MPD Permissions set May 30 18:37:12 volumio-czarny volumio[1013]: info: Asound.conf file unchanged, so no further update is needed May 30 18:37:12 volumio-czarny volumio[1013]: info: Output device has changed, restarting MPD May 30 18:37:12 volumio-czarny sudo[5805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 18:37:12 volumio-czarny sudo[5805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 18:37:12 volumio-czarny systemd[1]: shairport-sync.service: Succeeded. May 30 18:37:12 volumio-czarny systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 18:37:12 volumio-czarny sudo[5808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 18:37:12 volumio-czarny volumio[1013]: info: Output device has changed, restarting Shairport Sync May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 18:37:12 volumio-czarny volumio[1013]: [SpotifyConnect] Creating VLS config file May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 18:37:12 volumio-czarny sudo[5808]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5808]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny sudo[5811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 18:37:12 volumio-czarny sudo[5811]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5805]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 30 18:37:12 volumio-czarny systemd[1]: mpd.service: Succeeded. May 30 18:37:12 volumio-czarny systemd[1]: Stopped Music Player Daemon. May 30 18:37:12 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:12 volumio-czarny volumio[1013]: [SpotifyConnect] Restarting Vollibrespot Daemon May 30 18:37:12 volumio-czarny sudo[5823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:12 volumio-czarny sudo[5823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5823]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 30 18:37:12 volumio-czarny sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny sudo[5822]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny sudo[5822]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5822]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny sudo[5831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:12 volumio-czarny sudo[5831]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 30 18:37:12 volumio-czarny volumio[1013]: info: MPD Permissions set May 30 18:37:12 volumio-czarny volumio[1013]: info: Shairport-Sync Started May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny systemd[1]: Stopping Volspotconnect2 Daemon... May 30 18:37:12 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM May 30 18:37:12 volumio-czarny systemd[1]: volspotconnect.service: Succeeded. May 30 18:37:12 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:37:12 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:37:12 volumio-czarny sudo[5827]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny sudo[5831]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny volumio[5843]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 30 18:37:12 volumio-czarny volumio[5843]: Reading Config from "/tmp/volspotify.toml" May 30 18:37:12 volumio-czarny volumio[5843]: [Vollibrespot] : Using Alsa backend with device: volumio May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 30 18:37:12 volumio-czarny volumio[5843]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 30 18:37:12 volumio-czarny sudo[5851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 18:37:12 volumio-czarny sudo[5851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 30 18:37:12 volumio-czarny systemd[1]: mpd.service: Succeeded. May 30 18:37:12 volumio-czarny systemd[1]: Stopped Music Player Daemon. May 30 18:37:12 volumio-czarny volumio[1013]: info: Starting Shairport Sync May 30 18:37:12 volumio-czarny volumio[5843]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny sudo[5858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 18:37:12 volumio-czarny sudo[5858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 18:37:12 volumio-czarny systemd[1]: shairport-sync.service: Succeeded. May 30 18:37:12 volumio-czarny systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:12 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:12 volumio-czarny systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 18:37:12 volumio-czarny sudo[5858]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny volumio[1013]: info: Shairport-Sync Started May 30 18:37:12 volumio-czarny sudo[5854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:12 volumio-czarny sudo[5854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:12 volumio-czarny sudo[5854]: pam_unix(sudo:session): session closed for user root May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:12 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 30 18:37:13 volumio-czarny volumio[1013]: info: Preparing to generate the ALSA configuration file May 30 18:37:13 volumio-czarny systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 30 18:37:13 volumio-czarny systemd[1]: mpd.service: Failed with result 'protocol'. May 30 18:37:13 volumio-czarny systemd[1]: Failed to start Music Player Daemon. May 30 18:37:13 volumio-czarny sudo[5874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 30 18:37:13 volumio-czarny sudo[5874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny sudo[5851]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny sudo[5735]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny sudo[5874]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny sudo[5811]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Succeeded. May 30 18:37:13 volumio-czarny sudo[5882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /data/configuration/miscellanea/peppy_screensaver/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 30 18:37:13 volumio-czarny sudo[5882]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 30 18:37:13 volumio-czarny sudo[5882]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[1013]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 30 18:37:13 volumio-czarny volumio[1013]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 30 18:37:13 volumio-czarny volumio[1013]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 18:37:13 volumio-czarny volumio[1013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 30 18:37:13 volumio-czarny volumio[1013]: info: Reading ALSA contributions from plugins. May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:13 volumio-czarny sudo[5885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 18:37:13 volumio-czarny sudo[5885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:13 volumio-czarny sudo[5885]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[1013]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 30 18:37:13 volumio-czarny volumio[1013]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 30 18:37:13 volumio-czarny volumio[1013]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 30 18:37:13 volumio-czarny volumio[1013]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 30 18:37:13 volumio-czarny volumio[1013]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 30 18:37:13 volumio-czarny volumio[1013]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 30 18:37:13 volumio-czarny volumio[1013]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 30 18:37:13 volumio-czarny volumio[1013]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. May 30 18:37:13 volumio-czarny volumio[1013]: See "systemctl status mpd.service" and "journalctl -xe" for details. May 30 18:37:13 volumio-czarny volumio[1013]: info: MPD Permissions set May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny sudo[5878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:13 volumio-czarny sudo[5878]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny sudo[5878]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: Asound.conf file unchanged, so no further update is needed May 30 18:37:13 volumio-czarny volumio[1013]: info: Output device has changed, restarting MPD May 30 18:37:13 volumio-czarny sudo[5894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 18:37:13 volumio-czarny sudo[5894]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny sudo[5894]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[1013]: info: Output device has changed, restarting Shairport Sync May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:13 volumio-czarny sudo[5897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 18:37:13 volumio-czarny sudo[5897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 30 18:37:13 volumio-czarny systemd[1]: mpd.service: Succeeded. May 30 18:37:13 volumio-czarny systemd[1]: Stopped Music Player Daemon. May 30 18:37:13 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:13 volumio-czarny volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 18:37:13 volumio-czarny volumio[1013]: [SpotifyConnect] Creating VLS config file May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: [SpotifyConnect] Restarting Vollibrespot Daemon May 30 18:37:13 volumio-czarny sudo[5902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:13 volumio-czarny sudo[5902]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny sudo[5906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:13 volumio-czarny sudo[5906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny sudo[5902]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny sudo[5911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 30 18:37:13 volumio-czarny sudo[5911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:13 volumio-czarny volumio[1013]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 30 18:37:13 volumio-czarny volumio[1013]: info: MPD Permissions set May 30 18:37:13 volumio-czarny systemd[1]: Stopping Volspotconnect2 Daemon... May 30 18:37:13 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM May 30 18:37:13 volumio-czarny sudo[5914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 18:37:13 volumio-czarny systemd[1]: volspotconnect.service: Succeeded. May 30 18:37:13 volumio-czarny sudo[5914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:37:13 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:37:13 volumio-czarny volumio[5916]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 30 18:37:13 volumio-czarny volumio[5916]: Reading Config from "/tmp/volspotify.toml" May 30 18:37:13 volumio-czarny volumio[5916]: [Vollibrespot] : Using Alsa backend with device: volumio May 30 18:37:13 volumio-czarny sudo[5911]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny sudo[5906]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny sudo[5914]: pam_unix(sudo:session): session closed for user root May 30 18:37:13 volumio-czarny volumio[5916]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:13 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 30 18:37:13 volumio-czarny volumio[1013]: info: Starting Shairport Sync May 30 18:37:13 volumio-czarny volumio[5916]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 30 18:37:13 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 30 18:37:13 volumio-czarny sudo[5929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 18:37:13 volumio-czarny sudo[5929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:13 volumio-czarny sudo[5932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 18:37:13 volumio-czarny sudo[5932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:13 volumio-czarny systemd[1]: shairport-sync.service: Succeeded. May 30 18:37:13 volumio-czarny systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 18:37:13 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:13 volumio-czarny systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 18:37:13 volumio-czarny sudo[5929]: pam_unix(sudo:session): session closed for user root May 30 18:37:14 volumio-czarny volumio[1013]: info: Shairport-Sync Started May 30 18:37:14 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:14 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:14 volumio-czarny systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 30 18:37:14 volumio-czarny systemd[1]: mpd.service: Succeeded. May 30 18:37:14 volumio-czarny systemd[1]: Stopped Music Player Daemon. May 30 18:37:14 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 18:37:14 volumio-czarny systemd[1]: Starting Music Player Daemon... May 30 18:37:14 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:14 volumio-czarny sudo[5935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 18:37:14 volumio-czarny sudo[5935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:14 volumio-czarny sudo[5935]: pam_unix(sudo:session): session closed for user root May 30 18:37:14 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:14 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:37:14 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:14 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:14 volumio-czarny volumio[1013]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:14 volumio-czarny volumio[1013]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:14 volumio-czarny sudo[5963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 30 18:37:14 volumio-czarny sudo[5963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:14 volumio-czarny systemd[1]: Started Volumio Tidal Connect Service. May 30 18:37:14 volumio-czarny sudo[5963]: pam_unix(sudo:session): session closed for user root May 30 18:37:14 volumio-czarny mpd[5939]: May 30 18:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 18:37:14 volumio-czarny systemd[1]: Started Music Player Daemon. May 30 18:37:14 volumio-czarny mpd_monitor.sh[683]: MPD restarted due to no mpc output. May 30 18:37:14 volumio-czarny volumio[1013]: error: MPD error: The expression evaluated to a falsy value: May 30 18:37:14 volumio-czarny volumio[1013]: assert.ok(self.idling) May 30 18:37:14 volumio-czarny sudo[5932]: pam_unix(sudo:session): session closed for user root May 30 18:37:14 volumio-czarny volumio[1013]: error: The expression evaluated to a falsy value: May 30 18:37:14 volumio-czarny volumio[1013]: assert.ok(self.idling) May 30 18:37:14 volumio-czarny sudo[5897]: pam_unix(sudo:session): session closed for user root May 30 18:37:14 volumio-czarny volumio[1013]: error: updateQueue error: null May 30 18:37:14 volumio-czarny volumio[1013]: error: MPD error: The expression evaluated to a falsy value: May 30 18:37:14 volumio-czarny volumio[1013]: assert.ok(self.idling) May 30 18:37:14 volumio-czarny volumio[1013]: error: The expression evaluated to a falsy value: May 30 18:37:14 volumio-czarny volumio[1013]: assert.ok(self.idling) May 30 18:37:14 volumio-czarny volumio[1013]: error: updateQueue error: null May 30 18:37:15 volumio-czarny volumio[1013]: info: Executing endpoint tc_getconfig May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 30 18:37:15 volumio-czarny vtcs[5965]: STARTING TidalConnect services, version: 1.4.0.34 May 30 18:37:15 volumio-czarny vtcs[5965]: STARTED TidalConnect services. May 30 18:37:15 volumio-czarny volumio[1013]: info: Executing endpoint tc_connect May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 30 18:37:15 volumio-czarny volumio[1013]: info: Connecting to TidalConnect May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::servicePushState May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreStateMachine::pushState May 30 18:37:15 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioPushState May 30 18:37:15 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 30 18:37:15 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output May 30 18:37:15 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:15 volumio-czarny volumio[1013]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::servicePushState May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreStateMachine::pushState May 30 18:37:15 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:15 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioPushState May 30 18:37:15 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 30 18:37:15 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output May 30 18:37:15 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:15 volumio-czarny volumio[1013]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect May 30 18:37:15 volumio-czarny volumio[1013]: info: touch_display: Setting screensaver timeout to 0 seconds. May 30 18:37:15 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:15 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:15 volumio-czarny volumio[1013]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:15 volumio-czarny volumio[1013]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:15 volumio-czarny sudo[6000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 30 18:37:15 volumio-czarny sudo[6000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:15 volumio-czarny sudo[6000]: pam_unix(sudo:session): session closed for user root May 30 18:37:16 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:16 volumio-czarny volumio[1013]: info: TidalConnect service stoped! May 30 18:37:16 volumio-czarny volumio[1013]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:16 volumio-czarny volumio[1013]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 30 18:37:16 volumio-czarny sudo[6012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 30 18:37:16 volumio-czarny sudo[6012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:16 volumio-czarny sudo[6012]: pam_unix(sudo:session): session closed for user root May 30 18:37:17 volumio-czarny volumio[1013]: info: Checking Spotify Web API May 30 18:37:17 volumio-czarny volumio[1013]: info: TidalConnect service started! May 30 18:37:18 volumio-czarny volumio[1013]: info: Checking Spotify Web API May 30 18:37:18 volumio-czarny volumio[1013]: info: TidalConnect service started! May 30 18:37:19 volumio-czarny volumio[1013]: info: Checking Spotify Web API May 30 18:37:19 volumio-czarny volumio[1013]: info: TidalConnect service started! May 30 18:37:31 volumio-czarny volumio[1013]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf May 30 18:37:34 volumio-czarny volumio[1013]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf May 30 18:37:34 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify May 30 18:37:34 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Bandcamp Discover May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Calm Radio May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source YouTube2 May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source 80s80s Radio May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Mother Earth Radio May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Personal Radio May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source RADIO 357 May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Radio Paradise May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source Radio Rockserwis FM May 30 18:37:34 volumio-czarny volumio[1013]: Cannot find translation for source TIDAL May 30 18:37:34 volumio-czarny volumio[1013]: [SpotifyConnect] Closing metadata listener May 30 18:37:34 volumio-czarny sudo[6038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service May 30 18:37:34 volumio-czarny sudo[6038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:34 volumio-czarny systemd[1]: Stopping Volspotconnect2 Daemon... May 30 18:37:34 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM May 30 18:37:34 volumio-czarny systemd[1]: volspotconnect.service: Succeeded. May 30 18:37:34 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:37:34 volumio-czarny sudo[6038]: pam_unix(sudo:session): session closed for user root May 30 18:37:34 volumio-czarny volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 18:37:34 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service stoped! May 30 18:37:35 volumio-czarny volumio[1013]: info: Check plugin dependencies May 30 18:37:35 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 18:37:35 volumio-czarny volumio[1013]: info: Rename folder May 30 18:37:35 volumio-czarny volumio[1013]: info: Folder /tmp/downloaded_plugin.zip removed May 30 18:37:35 volumio-czarny volumio[1013]: info: Move to category May 30 18:37:37 volumio-czarny volumio[1013]: info: Checking if install.sh is present May 30 18:37:37 volumio-czarny volumio[1013]: info: Executing install.sh May 30 18:37:37 volumio-czarny sudo[6070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 30 18:37:37 volumio-czarny sudo[6070]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:37:37 volumio-czarny volumio[1013]: info: Installing Go-librespot May 30 18:37:37 volumio-czarny volumio[1013]: info: Checking old vollibrespot installs May 30 18:37:37 volumio-czarny systemd[1]: Reloading. May 30 18:37:38 volumio-czarny 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. May 30 18:37:38 volumio-czarny systemd[1]: Reloading. May 30 18:37:38 volumio-czarny 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. May 30 18:37:39 volumio-czarny volumio[1013]: info: Clearing old vollibrespot May 30 18:37:39 volumio-czarny volumio[1013]: info: vollibrespot cleared May 30 18:37:39 volumio-czarny volumio[1013]: info: Downloading daemon May 30 18:37:41 volumio-czarny volumio[1013]: info: Creating directories May 30 18:37:41 volumio-czarny volumio[1013]: info: Creating data directory May 30 18:37:41 volumio-czarny volumio[1013]: info: Creating Start Script May 30 18:37:41 volumio-czarny systemd[1]: Reloading. May 30 18:37:41 volumio-czarny 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. May 30 18:37:41 volumio-czarny sudo[6070]: pam_unix(sudo:session): session closed for user root May 30 18:37:41 volumio-czarny volumio[1013]: Plugin install end detected on script May 30 18:37:41 volumio-czarny volumio[1013]: info: Install script completed May 30 18:37:41 volumio-czarny volumio[1013]: info: Done installing plugin. May 30 18:37:41 volumio-czarny volumio[1013]: info: Enabling plugin spop May 30 18:37:41 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 18:37:41 volumio-czarny volumio[1013]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"miscellanea","version":"1.3.0","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bandcamp Discover","name":"bandcamp","category":"music_service","version":"1.1.1","icon":"fa-bandcamp","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.12","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO Rockserwis FM","name":"radrocksrvfm","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Squeezelite player","name":"squeezelite","category":"music_service","version":"2.0.0","icon":"fa fa-music","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.6.0","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.26","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.3.5","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] May 30 18:37:41 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 30 18:37:41 volumio-czarny volumio[1013]: info: Folder /tmp/plugins removed May 30 18:37:41 volumio-czarny volumio[1013]: info: Folder /tmp/downloaded_plugin.zip removed May 30 18:37:41 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:37:41 volumio-czarny volumio[1013]: info: Folder /data/temp removed May 30 18:37:54 volumio-czarny volumio[1013]: info: [1748630274737] [80s80s] Pushing the next song state Bananarama - Robert De Niro's Waiting and getting next track. May 30 18:37:54 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreCommandRouter::servicePushState May 30 18:37:54 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:54 volumio-czarny volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music125/v4/ee/32/9b/ee329b36-75b2-028f-fd2d-4271fc8545c1/source/600x600bb.jpg","name":"Bananarama - Robert De Niro's Waiting","title":"Robert De Niro's Waiting","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"202","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 30 18:37:54 volumio-czarny volumio[1013]: verbose: CURRENT POSITION 0 May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreStateMachine::syncState stateService play May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 30 18:37:54 volumio-czarny volumio[1013]: info: Received an update from plugin. extracting info from payload May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreStateMachine::pushState May 30 18:37:54 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioPushState May 30 18:37:54 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 30 18:37:54 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreStateMachine::pushState May 30 18:37:54 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:54 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioPushState May 30 18:37:54 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 30 18:37:54 volumio-czarny volumio[1013]: info: MRS: Pushing multiroomSync output May 30 18:37:54 volumio-czarny volumio[1013]: info: [1748630274788] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=1 May 30 18:37:54 volumio-czarny volumio[1013]: info: CorePlayQueue::getTrack 0 May 30 18:37:54 volumio-czarny volumio[1013]: info: Prefetching next song May 30 18:37:54 volumio-czarny volumio[1013]: info: [1748630274869] [80s80s] received new event containing 1 songs. May 30 18:37:54 volumio-czarny volumio[1013]: info: [1748630274869] [80s80s] PlayNextTrack API delay: 30 May 30 18:37:54 volumio-czarny volumio[1013]: info: [1748630274871] [80s80s] Setting timer to: 202000 milliseconds. May 30 18:37:58 volumio-czarny volumiologrotate[673]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 30 18:37:58 volumio-czarny volumiologrotate[673]: ls: cannot access 'CZARNY': No such file or directory May 30 18:38:01 volumio-czarny volumio[1013]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf May 30 18:38:03 volumio-czarny volumio[1013]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf May 30 18:38:03 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify May 30 18:38:03 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Bandcamp Discover May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Calm Radio May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source YouTube2 May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source 80s80s Radio May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Mother Earth Radio May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Personal Radio May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source RADIO 357 May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Radio Paradise May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source Radio Rockserwis FM May 30 18:38:03 volumio-czarny volumio[1013]: Cannot find translation for source TIDAL May 30 18:38:03 volumio-czarny volumio[1013]: [SpotifyConnect] Closing metadata listener May 30 18:38:03 volumio-czarny volumio[1013]: [SpotifyConnect] Failed to close UDP Socket for Spotify Connect: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running May 30 18:38:03 volumio-czarny sudo[6217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service May 30 18:38:03 volumio-czarny sudo[6217]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:38:03 volumio-czarny sudo[6217]: pam_unix(sudo:session): session closed for user root May 30 18:38:03 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service stoped! May 30 18:38:04 volumio-czarny volumio[1013]: info: Check plugin dependencies May 30 18:38:04 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 18:38:04 volumio-czarny volumio[1013]: info: Rename folder May 30 18:38:04 volumio-czarny volumio[1013]: info: Folder /tmp/downloaded_plugin.zip removed May 30 18:38:04 volumio-czarny volumio[1013]: info: Move to category May 30 18:38:06 volumio-czarny volumio[1013]: info: Checking if install.sh is present May 30 18:38:06 volumio-czarny volumio[1013]: info: Executing install.sh May 30 18:38:06 volumio-czarny sudo[6248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 30 18:38:06 volumio-czarny sudo[6248]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:38:06 volumio-czarny volumio[1013]: info: Installing Go-librespot May 30 18:38:06 volumio-czarny volumio[1013]: info: Checking old vollibrespot installs May 30 18:38:06 volumio-czarny systemd[1]: Reloading. May 30 18:38:06 volumio-czarny 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. May 30 18:38:06 volumio-czarny systemd[1]: Reloading. May 30 18:38:07 volumio-czarny 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. May 30 18:38:07 volumio-czarny volumio[1013]: info: Downloading daemon May 30 18:38:09 volumio-czarny volumio[1013]: info: Creating directories May 30 18:38:09 volumio-czarny volumio[1013]: info: Creating Start Script May 30 18:38:09 volumio-czarny systemd[1]: Reloading. May 30 18:38:09 volumio-czarny 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. May 30 18:38:10 volumio-czarny sudo[6248]: pam_unix(sudo:session): session closed for user root May 30 18:38:10 volumio-czarny volumio[1013]: info: Install script completed May 30 18:38:10 volumio-czarny volumio[1013]: info: Done installing plugin. May 30 18:38:10 volumio-czarny volumio[1013]: info: Enabling plugin spop May 30 18:38:10 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 18:38:10 volumio-czarny volumio[1013]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"miscellanea","version":"1.3.0","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bandcamp Discover","name":"bandcamp","category":"music_service","version":"1.1.1","icon":"fa-bandcamp","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.12","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO Rockserwis FM","name":"radrocksrvfm","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Squeezelite player","name":"squeezelite","category":"music_service","version":"2.0.0","icon":"fa fa-music","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.6.0","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.26","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.3.5","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] May 30 18:38:10 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 30 18:38:10 volumio-czarny volumio[1013]: Plugin install end detected on script May 30 18:38:10 volumio-czarny volumio[1013]: info: Folder /tmp/plugins removed May 30 18:38:10 volumio-czarny volumio[1013]: info: Folder /tmp/downloaded_plugin.zip removed May 30 18:38:10 volumio-czarny volumio[1013]: info: Folder /data/temp removed May 30 18:38:10 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:38:25 volumio-czarny volumio[1013]: info: Enabling plugin spop May 30 18:38:25 volumio-czarny volumio[1013]: info: Loading plugin "spop"... May 30 18:38:25 volumio-czarny volumio[1013]: info: PLUGIN START: spop May 30 18:38:25 volumio-czarny volumio[1013]: info: Checking for old spotify connect plugin installed May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 18:38:25 volumio-czarny volumio[1013]: [SpotifyConnect] Creating VLS config file May 30 18:38:25 volumio-czarny volumio[1013]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:25 volumio-czarny volumio[1013]: [SpotifyConnect] Starting metadata listener May 30 18:38:25 volumio-czarny volumio[1013]: (node:1013) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined May 30 18:38:25 volumio-czarny volumio[1013]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2607:27) May 30 18:38:25 volumio-czarny volumio[1013]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2470:14) May 30 18:38:25 volumio-czarny volumio[1013]: at Promise._successFn (/data/plugins/music_service/spop/index.js:134:14) May 30 18:38:25 volumio-czarny volumio[1013]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) May 30 18:38:25 volumio-czarny volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 30 18:38:25 volumio-czarny volumio[1013]: (Use `node --trace-warnings ...` to show where the warning was created) May 30 18:38:25 volumio-czarny volumio[1013]: (node:1013) 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) May 30 18:38:25 volumio-czarny volumio[1013]: (node:1013) [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. May 30 18:38:25 volumio-czarny sudo[6345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 30 18:38:25 volumio-czarny sudo[6345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:38:25 volumio-czarny volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 30 18:38:25 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:38:25 volumio-czarny volumio[6347]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory May 30 18:38:25 volumio-czarny sudo[6345]: pam_unix(sudo:session): session closed for user root May 30 18:38:25 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service started! May 30 18:38:25 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a May 30 18:38:25 volumio-czarny volumio[1013]: [Metrics] SpotifyConnect: 0s 126.76ms May 30 18:38:25 volumio-czarny systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 30 18:38:25 volumio-czarny volumio[1013]: info: Done. May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Bandcamp Discover May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Calm Radio May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source YouTube2 May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source 80s80s Radio May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Mother Earth Radio May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Personal Radio May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source RADIO 357 May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Radio Paradise May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source Radio Rockserwis FM May 30 18:38:25 volumio-czarny volumio[1013]: Cannot find translation for source TIDAL May 30 18:38:25 volumio-czarny volumio[1013]: [SpotifyConnect] Closing metadata listener May 30 18:38:25 volumio-czarny volumio[1013]: info: Disabling plugin spop May 30 18:38:25 volumio-czarny volumio[1013]: info: Done. May 30 18:38:25 volumio-czarny sudo[6350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service May 30 18:38:25 volumio-czarny sudo[6350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:38:25 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:38:25 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:38:25 volumio-czarny sudo[6350]: pam_unix(sudo:session): session closed for user root May 30 18:38:25 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service stoped! May 30 18:38:26 volumio-czarny volumio[1013]: info: Enabling plugin spop May 30 18:38:26 volumio-czarny volumio[1013]: info: Loading plugin "spop"... May 30 18:38:26 volumio-czarny volumio[1013]: info: PLUGIN START: spop May 30 18:38:26 volumio-czarny volumio[1013]: info: Checking for old spotify connect plugin installed May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 18:38:26 volumio-czarny volumio[1013]: [SpotifyConnect] Creating VLS config file May 30 18:38:26 volumio-czarny volumio[1013]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 18:38:26 volumio-czarny volumio[1013]: [SpotifyConnect] Starting metadata listener May 30 18:38:26 volumio-czarny volumio[1013]: (node:1013) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined May 30 18:38:26 volumio-czarny volumio[1013]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2607:27) May 30 18:38:26 volumio-czarny volumio[1013]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2470:14) May 30 18:38:26 volumio-czarny volumio[1013]: at Promise._successFn (/data/plugins/music_service/spop/index.js:134:14) May 30 18:38:26 volumio-czarny volumio[1013]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) May 30 18:38:26 volumio-czarny volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 30 18:38:26 volumio-czarny volumio[1013]: (node:1013) 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: 3) May 30 18:38:26 volumio-czarny sudo[6353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 30 18:38:26 volumio-czarny volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 30 18:38:26 volumio-czarny sudo[6353]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 18:38:26 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:38:26 volumio-czarny sudo[6353]: pam_unix(sudo:session): session closed for user root May 30 18:38:26 volumio-czarny volumio[6355]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory May 30 18:38:26 volumio-czarny volumio[1013]: [SpotifyConnect] Vollibrespot Daemon service started! May 30 18:38:26 volumio-czarny volumio[1013]: [Metrics] SpotifyConnect: 0s 144.05ms May 30 18:38:26 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a May 30 18:38:26 volumio-czarny volumio[1013]: info: Done. May 30 18:38:26 volumio-czarny systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 30 18:38:26 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:38:28 volumio-czarny systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 30 18:38:28 volumio-czarny systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. May 30 18:38:28 volumio-czarny systemd[1]: Stopped Volspotconnect2 Daemon. May 30 18:38:28 volumio-czarny systemd[1]: Started Volspotconnect2 Daemon. May 30 18:38:28 volumio-czarny volumio[6357]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory May 30 18:38:28 volumio-czarny systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a May 30 18:38:28 volumio-czarny systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 30 18:38:28 volumio-czarny volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:38:28 volumio-czarny volumio[1013]: error: Cannot populate Spotify configuration: TypeError: Cannot read property 'bars' of undefined May 30 18:38:29 volumio-czarny volumio[1013]: info: Checking Spotify Web API May 30 18:38:29 volumio-czarny volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:38:29 volumio-czarny volumio[1013]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running May 30 18:38:29 volumio-czarny volumio[1013]: at healthCheck (dgram.js:897:11) May 30 18:38:29 volumio-czarny volumio[1013]: at Socket.send (dgram.js:622:3) May 30 18:38:29 volumio-czarny volumio[1013]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) May 30 18:38:29 volumio-czarny volumio[1013]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) May 30 18:38:29 volumio-czarny volumio[1013]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2475:18) May 30 18:38:29 volumio-czarny volumio[1013]: at listOnTimeout (internal/timers.js:554:17) May 30 18:38:29 volumio-czarny volumio[1013]: at processTimers (internal/timers.js:497:7) { May 30 18:38:29 volumio-czarny volumio[1013]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' May 30 18:38:29 volumio-czarny volumio[1013]: } May 30 18:38:29 volumio-czarny volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:38:29 volumio-czarny sudo[6368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 18:37 May 30 18:38:29 volumio-czarny sudo[6368]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"