-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-02-19 14:18:45 CET. -- Feb 19 14:18:09 salon systemd-timedated[980]: Changed local time to Wed Feb 19 14:18:09 2025 Feb 19 14:18:09 salon sudo[964]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:09 salon volumio-time-update[610]: volumio-time-update-util: System time updated successfully. Feb 19 14:18:09 salon systemd[1]: Started Volumio Time Update Utility. Feb 19 14:18:09 salon systemd[1]: Starting Daily man-db regeneration... Feb 19 14:18:09 salon systemd[1]: Starting Daily apt download activities... Feb 19 14:18:09 salon systemd[1]: man-db.service: Succeeded. Feb 19 14:18:09 salon systemd[1]: Started Daily man-db regeneration. Feb 19 14:18:09 salon ntpd[821]: Listen normally on 3 eth0 192.168.1.91:123 Feb 19 14:18:09 salon ntpd[821]: Listen normally on 4 wlan0 192.168.211.1:123 Feb 19 14:18:09 salon ntpd[821]: new interface(s) found: waking up resolver Feb 19 14:18:10 salon nmbd[789]: [2025/02/19 14:18:10.207839, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 14:18:10 salon nmbd[789]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 19 14:18:10 salon systemd[1]: Started Samba NMB Daemon. Feb 19 14:18:10 salon systemd[1]: Starting Samba Winbind Daemon... Feb 19 14:18:10 salon nmbd[789]: [2025/02/19 14:18:10.280710, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 19 14:18:10 salon nmbd[789]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.91 for name WORKGROUP<1d>. Feb 19 14:18:10 salon nmbd[789]: This response was from IP 192.168.1.224, reporting an IP address of 192.168.1.224. Feb 19 14:18:10 salon winbindd[1010]: [2025/02/19 14:18:10.795093, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 19 14:18:10 salon winbindd[1010]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 19 14:18:10 salon winbindd[1010]: [2025/02/19 14:18:10.884789, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 14:18:10 salon winbindd[1010]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 19 14:18:10 salon systemd[1]: Started Samba Winbind Daemon. Feb 19 14:18:10 salon systemd[1]: Starting Samba SMB Daemon... Feb 19 14:18:11 salon smbd[1015]: [2025/02/19 14:18:11.855723, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 14:18:11 salon systemd[1]: Started Samba SMB Daemon. Feb 19 14:18:11 salon smbd[1015]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 19 14:18:11 salon systemd[1]: Reached target Multi-User System. Feb 19 14:18:11 salon systemd[1]: Reached target Graphical Interface. Feb 19 14:18:11 salon systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 19 14:18:11 salon systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 19 14:18:11 salon systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 19 14:18:12 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:12 salon volumio[957]: info: ----- Volumio3 ---- Feb 19 14:18:12 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:12 salon volumio[957]: info: ----- System startup ---- Feb 19 14:18:12 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:13 salon systemd[1]: apt-daily.service: Succeeded. Feb 19 14:18:13 salon systemd[1]: Started Daily apt download activities. Feb 19 14:18:13 salon systemd[1]: Starting Daily apt upgrade and clean activities... Feb 19 14:18:14 salon volumio[957]: info: MYVOLUMIO Environment detected Feb 19 14:18:14 salon volumio[957]: info: Plugin folders cleanup Feb 19 14:18:14 salon volumio[957]: info: Scanning into folder /volumio/app/plugins/ Feb 19 14:18:14 salon volumio[957]: info: Scanning category audio_interface Feb 19 14:18:14 salon volumio[957]: info: Scanning category miscellanea Feb 19 14:18:14 salon volumio[957]: info: Scanning category music_service Feb 19 14:18:14 salon volumio[957]: info: Scanning category plugins.json Feb 19 14:18:14 salon volumio[957]: info: Scanning category system_controller Feb 19 14:18:14 salon volumio[957]: info: Scanning category user_interface Feb 19 14:18:14 salon volumio[957]: info: Scanning into folder /data/plugins/ Feb 19 14:18:14 salon volumio[957]: info: Scanning category music_service Feb 19 14:18:14 salon volumio[957]: info: Scanning category user_interface Feb 19 14:18:14 salon volumio[957]: info: Plugin folders cleanup completed Feb 19 14:18:14 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:14 salon volumio[957]: info: ----- Core plugins startup ---- Feb 19 14:18:14 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:14 salon volumio[957]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 14:18:14 salon volumio[957]: info: Adding plugin upnp to MyMusic Plugins Feb 19 14:18:14 salon volumio[957]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 14:18:14 salon volumio[957]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 14:18:14 salon volumio[957]: info: Loading plugins from folder /data/plugins/ Feb 19 14:18:14 salon volumio[957]: info: Loading plugin "system"... Feb 19 14:18:14 salon volumio[957]: info: Loading plugin "appearance"... Feb 19 14:18:15 salon systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 19 14:18:15 salon systemd[1]: Started Daily apt upgrade and clean activities. Feb 19 14:18:15 salon systemd[1]: Startup finished in 14.274s (kernel) + 18.118s (userspace) = 32.393s. Feb 19 14:18:15 salon volumio-remote-updater[640]: [2025-02-19 14:18:15] [connect] Successful connection Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "network"... Feb 19 14:18:16 salon volumio[957]: info: Refreshing Cached IP Addresses Feb 19 14:18:16 salon sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 14:18:16 salon sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:16 salon sudo[1115]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:16 salon sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 14:18:16 salon sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:16 salon sudo[1117]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "services"... Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "alsa_controller"... Feb 19 14:18:16 salon sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 14:18:16 salon sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:16 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "wizard"... Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "networkfs"... Feb 19 14:18:16 salon volumio[957]: info: Starting Udev Watcher for removable devices Feb 19 14:18:16 salon sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Alphanet@1525!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.170/Muzyka /mnt/NAS/QNAP_Music Feb 19 14:18:16 salon sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:16 salon volumio[957]: info: Ignoring mount for partition: boot Feb 19 14:18:16 salon volumio[957]: info: Ignoring mount for partition: volumio Feb 19 14:18:16 salon volumio[957]: info: Ignoring mount for partition: volumio_data Feb 19 14:18:16 salon volumio[957]: info: Mounting Device MediaBOX Feb 19 14:18:16 salon sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/MediaBOX -o noatime Feb 19 14:18:16 salon sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:16 salon kernel: Key type cifs.spnego registered Feb 19 14:18:16 salon kernel: Key type cifs.idmap registered Feb 19 14:18:16 salon kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 19 14:18:16 salon kernel: CIFS: Attempting to mount //192.168.1.170/Muzyka Feb 19 14:18:16 salon kernel: EXT4-fs (sda1): recovery complete Feb 19 14:18:16 salon kernel: EXT4-fs (sda1): mounted filesystem c035df55-2b76-4084-82b4-e254fcdf2576 r/w with ordered data mode. Quota mode: none. Feb 19 14:18:16 salon sudo[1153]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:16 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "volumio_command_line_client"... Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "upnp"... Feb 19 14:18:16 salon volumio[957]: info: [1739971096806] Starting Upmpd Daemon Feb 19 14:18:16 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "my_music"... Feb 19 14:18:16 salon volumio[957]: info: Loading plugin "mpd"... Feb 19 14:18:17 salon kernel: cryptd: max_cpu_qlen set to 1000 Feb 19 14:18:17 salon sudo[1126]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:17 salon volumio[957]: info: Loading plugin "upnp_browser"... Feb 19 14:18:17 salon volumio[957]: info: Loading plugin "alarm-clock"... Feb 19 14:18:17 salon sudo[1143]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "airplay_emulation"... Feb 19 14:18:18 salon volumio[957]: info: Starting Shairport Sync Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "last_100"... Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "webradio"... Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "i2s_dacs"... Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "volumiodiscovery"... Feb 19 14:18:18 salon volumio[957]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 14:18:18 salon volumio[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 14:18:18 salon volumio[957]: *** WARNING *** For more information see Feb 19 14:18:18 salon volumio[957]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 14:18:18 salon volumio[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 14:18:18 salon volumio[957]: *** WARNING *** For more information see Feb 19 14:18:18 salon node[957]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 14:18:18 salon node[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 14:18:18 salon node[957]: *** WARNING *** For more information see Feb 19 14:18:18 salon node[957]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 14:18:18 salon node[957]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 14:18:18 salon node[957]: *** WARNING *** For more information see Feb 19 14:18:18 salon volumio[957]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 14:18:18 salon volumio[957]: info: Discovery: Started advertising with name: salon Feb 19 14:18:18 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 14:18:18 salon volumio[957]: info: Loading plugin "spop"... Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "outputs"... Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "albumart"... Feb 19 14:18:20 salon volumio[957]: info: Plugin example_plugin is not enabled Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "inputs"... Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "updater_comm"... Feb 19 14:18:20 salon volumio[957]: info: Plugin mpdemulation is not enabled Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "rest_api"... Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "websocket"... Feb 19 14:18:20 salon volumio[957]: info: Starting Socket.io Server version 2.3.0 Feb 19 14:18:20 salon volumio[957]: info: Loading plugin "rad357pl"... Feb 19 14:18:20 salon volumio[957]: Forking 3 albumart workers Feb 19 14:18:21 salon volumio[957]: info: Applying required configuration parameters for plugin rad357pl Feb 19 14:18:21 salon volumio[957]: info: [1739971101279] [rad357pl] API delay: 5 Feb 19 14:18:21 salon volumio[957]: info: Loading plugin "touch_display"... Feb 19 14:18:21 salon volumio[957]: info: Applying required configuration parameters for plugin touch_display Feb 19 14:18:21 salon volumio[957]: Starting albumart workers Feb 19 14:18:21 salon volumio[957]: Starting albumart workers Feb 19 14:18:21 salon volumio[957]: Starting albumart workers Feb 19 14:18:22 salon volumio[957]: info: Loading i18n strings for locale pl Feb 19 14:18:22 salon volumio[957]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 19 14:18:22 salon volumio[957]: Updating browse sources language Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::initPlayerControls Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:18:22 salon volumio[957]: Express server listening on port 3000 Feb 19 14:18:22 salon volumio[957]: [Metrics] WebUI: 10s 995.86ms Feb 19 14:18:22 salon volumio[957]: info: CoreStateMachine::resetVolumioState Feb 19 14:18:22 salon volumio[957]: info: CoreStateMachine::getcurrentVolume Feb 19 14:18:22 salon volumio[957]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 14:18:22 salon volumio[957]: info: Volumio Network Manager: Network status updated: 1 Feb 19 14:18:23 salon volumio[957]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: Reloading queue from file Feb 19 14:18:23 salon volumio[957]: info: VolumeController:: Volume=32 Mute =false Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::pushState Feb 19 14:18:23 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioPushState Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::updateTrackBlock Feb 19 14:18:23 salon volumio[957]: info: CorePlayQueue::getTrackBlock Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 14:18:23 salon volumio[957]: info: Setting Device type: Raspberry PI Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::setRepeat null single undefined Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::pushState Feb 19 14:18:23 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioPushState Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::setRandom null Feb 19 14:18:23 salon volumio[957]: info: CoreStateMachine::pushState Feb 19 14:18:23 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioPushState Feb 19 14:18:23 salon volumio[957]: info: Completed loading Core Plugins Feb 19 14:18:23 salon volumio[957]: info: Preparing to generate the ALSA configuration file Feb 19 14:18:23 salon volumio[957]: info: Asound.conf file unchanged, so no further update is needed Feb 19 14:18:23 salon volumio[957]: info: Output device has changed, restarting MPD Feb 19 14:18:23 salon volumio[957]: info: Output device has changed, restarting Shairport Sync Feb 19 14:18:23 salon sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 14:18:23 salon sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:23 salon sudo[1262]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:23 salon sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:18:23 salon sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon systemd[1]: Stopping Music Player Daemon... Feb 19 14:18:23 salon volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:18:23 salon volumio[957]: info: ___________ START PLUGINS ___________ Feb 19 14:18:23 salon volumio[957]: info: ControllerMpd::onStart: Initializing MPD Feb 19 14:18:23 salon volumio[957]: info: Creating MPD Configuration file Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 14:18:23 salon volumio[957]: info: [1739971103461] CoreMusicLibrary::Adding element Serwery Mediów Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:23 salon sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 14:18:23 salon sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon sudo[1271]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:23 salon sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 14:18:23 salon sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:23 salon systemd[1]: mpd.service: Succeeded. Feb 19 14:18:23 salon systemd[1]: Stopped Music Player Daemon. Feb 19 14:18:23 salon volumio[957]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 14:18:23 salon volumio[957]: info: [1739971103614] CoreMusicLibrary::Adding element Last_100 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 14:18:23 salon volumio[957]: info: [1739971103618] CoreMusicLibrary::Adding element Webradio Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 14:18:23 salon volumio[957]: info: Initializing BBC Radios Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 14:18:23 salon systemd[1]: Starting Music Player Daemon... Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: Creating Spotify config file Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 14:18:23 salon volumio[957]: info: [1739971103809] CoreMusicLibrary::Adding element RADIO 357 Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:23 salon volumio[957]: Cannot find translation for source RADIO 357 Feb 19 14:18:23 salon sudo[1282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 14:18:23 salon sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon volumio[957]: info: Loading i18n strings for locale pl Feb 19 14:18:23 salon sudo[1282]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:23 salon volumio[957]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 19 14:18:23 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 14:18:23 salon volumio[957]: info: Volumio Calling Home Feb 19 14:18:23 salon sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 19 14:18:23 salon sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:23 salon sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 19 14:18:23 salon sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 19 14:18:24 salon sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon volumio[957]: info: Discovery: adding 3a1c01af-f01b-4494-8508-7fd58c899edd Feb 19 14:18:24 salon volumio[957]: info: Discovery: Found device salon Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:24 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:24 salon volumio[957]: info: VolumeController:: Volume=32 Mute =false Feb 19 14:18:24 salon volumio[957]: info: CoreStateMachine::pushState Feb 19 14:18:24 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::volumioPushState Feb 19 14:18:24 salon sudo[1299]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:24 salon volumio[957]: info: Discovery: this is already registered, 3a1c01af-f01b-4494-8508-7fd58c899edd Feb 19 14:18:24 salon volumio[957]: info: Discovery: Found device salon Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:24 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:24 salon systemd[1]: Reloading. Feb 19 14:18:24 salon volumio[957]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Feb 19 14:18:24 salon volumio[957]: info: MPD Permissions set Feb 19 14:18:24 salon volumio[957]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 19 14:18:24 salon volumio[957]: info: Spotify config file written Feb 19 14:18:24 salon sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 19 14:18:24 salon sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon volumio[957]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 19 14:18:24 salon volumio[957]: info: Volumio called home Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 14:18:24 salon volumio[957]: info: No need to fix Spotify hosts Feb 19 14:18:24 salon 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. Feb 19 14:18:24 salon volumio[957]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 19 14:18:24 salon volumio[957]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 14:18:24 salon volumio[957]: SPOTIFY: BQCGFU_8W9LzFomC0nWWS4eehiye_cwHu3X33QQh98Uy0AmMOGKpUnKK_rE4KawPhWDDGtTwsOBUVLrVtHtQTbH8SSVK74-WUaVqU44WOirSCijfhwkhs0vwq015-TDvlnTBqqQfYdxCkQpXNiW6rID5EwDRSCQ-0OAInp9yqyKeqStCMm1xjTv7QxJVtk4-F0ZhV_cdLR_DdpSxoBtaRXItkRoodje73mrAxbgJrcnGwxrlzLMUiwuUxs98YHFSmjeEUHV9LoSD8ApX66doUt1vZWuMoj3gXQiQuZ4 Feb 19 14:18:24 salon volumio[957]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 14:18:24 salon volumio[957]: info: New Spotify access token = BQCGFU_8W9LzFomC0nWWS4eehiye_cwHu3X33QQh98Uy0AmMOGKpUnKK_rE4KawPhWDDGtTwsOBUVLrVtHtQTbH8SSVK74-WUaVqU44WOirSCijfhwkhs0vwq015-TDvlnTBqqQfYdxCkQpXNiW6rID5EwDRSCQ-0OAInp9yqyKeqStCMm1xjTv7QxJVtk4-F0ZhV_cdLR_DdpSxoBtaRXItkRoodje73mrAxbgJrcnGwxrlzLMUiwuUxs98YHFSmjeEUHV9LoSD8ApX66doUt1vZWuMoj3gXQiQuZ4 Feb 19 14:18:24 salon volumio[957]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 19 14:18:24 salon sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 19 14:18:24 salon sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon sudo[1332]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:24 salon volumio[957]: info: Starting Shairport Sync Feb 19 14:18:24 salon volumio[957]: info: Starting Shairport Sync Feb 19 14:18:24 salon volumio[957]: info: Starting Shairport Sync Feb 19 14:18:24 salon sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:18:24 salon sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:18:24 salon sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 14:18:24 salon sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:24 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:24 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:24 salon volumio[957]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 19 14:18:25 salon volumio[957]: SPOTIFY: User informations: {"country":"PL","display_name":"Pawel","email":"urbaniak.pawel@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/h3bis9njfxrfetk8j5n95v90d"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/h3bis9njfxrfetk8j5n95v90d","id":"h3bis9njfxrfetk8j5n95v90d","images":[],"product":"premium","type":"user","uri":"spotify:user:h3bis9njfxrfetk8j5n95v90d"} Feb 19 14:18:25 salon volumio[957]: info: Spotify Successfully logged in Feb 19 14:18:25 salon volumio[957]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 14:18:25 salon volumio[957]: info: [1739971105193] CoreMusicLibrary::Adding element Spotify Feb 19 14:18:25 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 14:18:25 salon volumio[957]: Cannot find translation for source RADIO 357 Feb 19 14:18:25 salon volumio[957]: Cannot find translation for source Spotify Feb 19 14:18:25 salon systemd[1]: Reloading. Feb 19 14:18:25 salon sudo[1305]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:25 salon volumio[957]: info: touch_display: systemctl daemon-reload succeeded. Feb 19 14:18:25 salon sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 19 14:18:25 salon sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:25 salon 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. Feb 19 14:18:25 salon mpd[1294]: Feb 19 14:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 14:18:26 salon sudo[1301]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon volumio[957]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 19 14:18:26 salon systemd[1]: Started Volumio Kiosk. Feb 19 14:18:26 salon sudo[1346]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 14:18:26 salon volumio[957]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 19 14:18:26 salon volumio[957]: info: touch_display: Volumio Kiosk started. Feb 19 14:18:26 salon systemd[1]: Started go-librespot Daemon. Feb 19 14:18:26 salon sudo[1308]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon go-librespot[1379]: Librespot-go daemon starting... Feb 19 14:18:26 salon systemd[1]: Started Music Player Daemon. Feb 19 14:18:26 salon systemd[1]: shairport-sync.service: Succeeded. Feb 19 14:18:26 salon sudo[1273]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:26 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:26 salon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 14:18:26 salon sudo[1264]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:26 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:26 salon volumio[957]: info: Completed starting Core Plugins Feb 19 14:18:26 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:26 salon volumio[957]: info: ----- MyVolumio plugins startup ---- Feb 19 14:18:26 salon volumio[957]: info: ------------------------------------------- Feb 19 14:18:26 salon volumio[957]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 14:18:26 salon volumio[957]: info: MPD running with PID1294 Feb 19 14:18:26 salon volumio[957]: ,establishing connection Feb 19 14:18:26 salon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 14:18:26 salon sudo[1340]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon sudo[1335]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon sudo[1337]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon volumio[957]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:18:26 salon volumio[957]: assert.ok(self.idling) Feb 19 14:18:26 salon volumio[957]: error: The expression evaluated to a falsy value: Feb 19 14:18:26 salon volumio[957]: assert.ok(self.idling) Feb 19 14:18:26 salon volumio[957]: error: MPD error: The expression evaluated to a falsy value: Feb 19 14:18:26 salon volumio[957]: assert.ok(self.idling) Feb 19 14:18:26 salon volumio[957]: error: The expression evaluated to a falsy value: Feb 19 14:18:26 salon volumio[957]: assert.ok(self.idling) Feb 19 14:18:26 salon sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 14:18:26 salon sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:26 salon sudo[1417]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon volumio[957]: info: touch_display: X display number found: 0 Feb 19 14:18:26 salon volumio[957]: info: touch_display: X display number found: 0 Feb 19 14:18:26 salon volumio[957]: info: Shairport-Sync Started Feb 19 14:18:26 salon sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 14:18:26 salon volumio[957]: Error adding Membership: Error: addMembership EINVAL Feb 19 14:18:26 salon volumio[957]: info: Shairport-Sync Started Feb 19 14:18:26 salon sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:26 salon volumio[957]: info: Shairport-Sync Started Feb 19 14:18:26 salon volumio[957]: error: updateQueue error: null Feb 19 14:18:26 salon sudo[1419]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:26 salon startx[1375]: X.Org X Server 1.20.4 Feb 19 14:18:26 salon startx[1375]: X Protocol Version 11, Revision 0 Feb 19 14:18:26 salon startx[1375]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Feb 19 14:18:26 salon startx[1375]: Current Operating System: Linux salon 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Feb 19 14:18:26 salon startx[1375]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1366 bcm2708_fb.fbheight=768 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=e8266d03-7fa6-4737-8e40-bda81aa20d1d imgfile=/volumio_current.sqsh bootpart=UUID=3E9A-8A36 datapart=UUID=afd223a2-9e18-4a3a-a657-c393b23d48b5 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 19 14:18:26 salon startx[1375]: Build Date: 04 April 2023 07:50:56AM Feb 19 14:18:26 salon startx[1375]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Feb 19 14:18:26 salon startx[1375]: Current version of pixman: 0.36.0 Feb 19 14:18:26 salon startx[1375]: Before reporting problems, check http://wiki.x.org Feb 19 14:18:26 salon startx[1375]: to make sure that you have the latest version. Feb 19 14:18:26 salon startx[1375]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 19 14:18:26 salon startx[1375]: (++) from command line, (!!) notice, (II) informational, Feb 19 14:18:26 salon startx[1375]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 19 14:18:26 salon startx[1375]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Feb 19 14:18:26 2025 Feb 19 14:18:26 salon sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 19 14:18:26 salon sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:26 salon startx[1375]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 19 14:18:26 salon startx[1375]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 19 14:18:27 salon volumio[957]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 19 14:18:27 salon systemd[1]: Started UPnP Renderer front-end to MPD. Feb 19 14:18:27 salon sudo[1423]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:27 salon volumio[957]: info: Upmpdcli Daemon Started Feb 19 14:18:27 salon volumio[957]: info: touch_display: X display number found: 0 Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=info msg="generated new device id: 64e1da78c7c289815caba42e00bed0aa09cf92c2" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="stored credentials found for h3bis9njfxrfetk8j5n95v90d" Feb 19 14:18:27 salon volumio[1425]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="zeroconf server listening on port 41359" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="obtained new client token: AAAK3Vw5l7oDdbXBUhyRxhT/0yRLRbhAnIPjnbryRZu00muqA5VvjrVTK55pyPoC7UPrKqKXVp/pJse7TV4r29ZRLe2zk1pv/ZAVxyhTbUHqXe/AcidArPZJVrHsP+cmvZncwDgnT5vshOGIzWDGUCJEWYA1T7VBoXADBlzj/YFTuOilEWg7vmjZkzDSb4iTvLj2+OfpTbVelgxiH+80YwiXBGhi3AQTheKhNsgWDn/TsX8040C8KcxOvDw=" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 19 14:18:27 salon go-librespot[1379]: time="2025-02-19T14:18:27+01:00" level=debug msg="completed keyexchange" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="completed challenge" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="authenticated as h3bis9njfxrfetk8j5n95v90d" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="authenticated as h3bis9njfxrfetk8j5n95v90d" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="dealer connection opened" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="initializing zeroconf session, username: h3bis9njfxrfetk8j5n95v90d" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="autoplay enabled: false" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="received connection id: N2Q3YmUzODItM2QxNy00MGIxLWI4ZTctMDliZjg1YzhlY2M5K2RlYWxlcit0Y3A6Ly8wYWNhNWEzNS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRDBGNDg0MTk0MkVBREIzQkQxRUZCNkRBRjNGQ0Q3QzkzMEM1OUM0RkNGREQyRTY4MDRFOTk3NkZCQURBMTg2OQ==" Feb 19 14:18:28 salon go-librespot[1379]: time="2025-02-19T14:18:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 19 14:18:28 salon volumio[957]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 19 14:18:29 salon volumio[957]: info: go-librespot daemon successfully initialized Feb 19 14:18:30 salon systemd[1]: systemd-fsckd.service: Succeeded. Feb 19 14:18:30 salon volumio-remote-updater[640]: [2025-02-19 14:18:30] [connect] Successful connection Feb 19 14:18:30 salon volumio-remote-updater[640]: [2025-02-19 14:18:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739971110 101 Feb 19 14:18:30 salon volumio[957]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Feb 19 14:18:32 salon volumio[957]: info: Initializing connection to go-librespot Websocket Feb 19 14:18:33 salon go-librespot[1379]: time="2025-02-19T14:18:33+01:00" level=debug msg="new websocket client" Feb 19 14:18:33 salon volumio[957]: info: Connection to go-librespot Websocket established Feb 19 14:18:34 salon systemd[1]: systemd-hostnamed.service: Succeeded. Feb 19 14:18:36 salon volumio[957]: info: Getting Spotify volume Feb 19 14:18:37 salon volumio[957]: info: Spotify volume: 100 Feb 19 14:18:37 salon volumio[957]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 19 14:18:38 salon volumio[957]: info: Adding plugin bluetooth to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: Adding plugin multiroom to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: Adding plugin metavolumio to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: Adding plugin cd_controller to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 19 14:18:38 salon volumio[957]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 19 14:18:39 salon systemd[1]: systemd-timedated.service: Succeeded. Feb 19 14:18:39 salon volumio[1425]: ...........................................................................................++++ Feb 19 14:18:41 salon volumio[957]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 19 14:18:41 salon volumio[957]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 19 14:18:41 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:41 salon volumio[957]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 14:18:41 salon volumio[957]: info: Starting MyVolumio Remote Streaming Endpoints Feb 19 14:18:41 salon volumio[957]: info: MyVolumio login type: Token Feb 19 14:18:41 salon volumio[957]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 19 14:18:41 salon volumio[957]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 19 14:18:41 salon go-librespot[1379]: time="2025-02-19T14:18:41+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Feb 19 14:18:41 salon go-librespot[1379]: time="2025-02-19T14:18:41+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.91:46196->34.158.1.133:4070: use of closed network connection" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="obtained new client token: AAC7wSjQwjY8X3NzdE9sPaJSrEMWBEeIOag0eHgnMoVvx2iqcLOJsY9a4zbGX8iqi9Vze3wqDXnAPrCY/J5AseWn8wyLzKJeBr0xC68pMoBmxVzYJvrqTznje7AZWyhk/vPygvTq9O35Pu6KKJSfHFYlHmR5NRHa6Ayrf/pLzuzTBOoEC2Ho8YhGoIyu7g+3Q10R5jgk86x3q6oNbGVWpbreO0I12UhYBqjReG0hOVS8IIwSB9+SgDrNYsrjYg==" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="completed keyexchange" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="completed challenge" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="authenticated as vmhl71idjkbkf2jko6onxqe0w" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="authenticated as vmhl71idjkbkf2jko6onxqe0w" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="dealer connection opened" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=info msg="accepted zeroconf user vmhl71idjkbkf2jko6onxqe0w from Redmi Note 9 Pro" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="autoplay enabled: false" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="received connection id: NDZlZDNiZmQtYmQxZi00NTczLThhNDctZjk4NmQxOGIyMjVjK2RlYWxlcit0Y3A6Ly8wYWNhNWEzNi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArOTk2NEQ3NDFCOUM4ODgyMzhGNDIwQzZERDAzREJBRTlGREU2REJCQTIwQUM2MDNDQjJGNEY3OTVBQzU2OTg5RQ==" Feb 19 14:18:42 salon go-librespot[1379]: time="2025-02-19T14:18:42+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 19 14:18:43 salon volumio[957]: info: Starting Streaming Service Transparent Proxy Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="handling transfer player command from 6250a37922fa0ff8c2ccf71b72f4b96e597e3465" Feb 19 14:18:43 salon volumio[957]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 19 14:18:43 salon volumio[957]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 19 14:18:43 salon volumio[957]: info: Streaming services startup Feb 19 14:18:43 salon volumio[957]: info: Starting Streaming Daemon Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:6bWBwtrFJ3FZI7WVZQfI3b" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=trace msg="fetched new page 0 with 60 items (list: 60)" uri="spotify:playlist:6bWBwtrFJ3FZI7WVZQfI3b" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=trace msg="emitting websocket event: will_play" Feb 19 14:18:43 salon volumio[957]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 19 14:18:43 salon sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 19 14:18:43 salon sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="selected format OGG_VORBIS_320 (fa797dc0d53365b211fb4ee86c28572d2f8f4ef4)" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="requested aes key for file fa797dc0d53365b211fb4ee86c28572d2f8f4ef4, gid: 0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1688" Feb 19 14:18:43 salon sudo[1657]: pam_unix(sudo:session): session closed for user root Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1287" Feb 19 14:18:43 salon volumio[957]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0KzAbK6nItSqNh8q70tb0K","play_origin":"playlist"}} Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=debug msg="fetched first chunk of 20, total size is 10176596 bytes" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:43 salon go-librespot[1379]: time="2025-02-19T14:18:43+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="created new output device" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=info msg="loaded track \"Where Is My Mind?\" (paused: true, position: 0ms, duration: 229226ms, prefetched: false)" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="emitting websocket event: metadata" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="emitting websocket event: active" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="sending successful reply for dealer request" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="emitting websocket event: paused" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:44 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:44 salon volumio[957]: error: Cannot start Volumio Streaming Daemon Feb 19 14:18:44 salon volumio[957]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 19 14:18:44 salon volumio[957]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="handling resume player command from 6250a37922fa0ff8c2ccf71b72f4b96e597e3465" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0KzAbK6nItSqNh8q70tb0K" Feb 19 14:18:44 salon kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="resume track at 316ms" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="scheduling prefetch in 199s" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="sending successful reply for dealer request" Feb 19 14:18:44 salon volumio[957]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0KzAbK6nItSqNh8q70tb0K","name":"Where Is My Mind?","artist_names":["Pixies"],"album_name":"Death to the Pixies","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b17d34882944eaf0695153f2","position":0,"duration":229226,"release_date":"year:1997 month:10 day:6","track_number":14,"disc_number":1}} Feb 19 14:18:44 salon volumio[957]: SPOTIFY: received: {"type":"active","data":null} Feb 19 14:18:44 salon volumio[957]: info: Aligning Spotify Volume to Volumio Volume Feb 19 14:18:44 salon volumio[957]: info: CoreCommandRouter::volumioGetState Feb 19 14:18:44 salon volumio[957]: info: CorePlayQueue::getTrack 0 Feb 19 14:18:44 salon volumio[957]: info: Setting Spotify Volume from Volumio: 32 Feb 19 14:18:44 salon volumio[957]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0KzAbK6nItSqNh8q70tb0K","play_origin":"playlist"}} Feb 19 14:18:44 salon volumio[957]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 14:18:44 salon volumio[957]: TypeError: Cannot read property 'service' of undefined Feb 19 14:18:44 salon volumio[957]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Feb 19 14:18:44 salon volumio[957]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:271:18) Feb 19 14:18:44 salon volumio[957]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Feb 19 14:18:44 salon volumio[957]: at WebSocket.emit (events.js:315:20) Feb 19 14:18:44 salon volumio[957]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 19 14:18:44 salon volumio[957]: at Receiver.emit (events.js:315:20) Feb 19 14:18:44 salon volumio[957]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 19 14:18:44 salon volumio[957]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 19 14:18:44 salon volumio[957]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 19 14:18:44 salon volumio[957]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 19 14:18:44 salon volumio[957]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 19 14:18:44 salon volumio[957]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 19 14:18:44 salon volumio[957]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 19 14:18:44 salon volumio[957]: at Socket.emit (events.js:315:20) Feb 19 14:18:44 salon volumio[957]: at addChunk (internal/streams/readable.js:309:12) Feb 19 14:18:44 salon volumio[957]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 19 14:18:44 salon volumio[957]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 14:18:44 salon go-librespot[1379]: time="2025-02-19T14:18:44+01:00" level=trace msg="emitting websocket event: playing" Feb 19 14:18:45 salon volumio[957]: STREAMING PROXY: Starting server on port 3245 Feb 19 14:18:45 salon volumio[957]: Node JS runtime: 14 Feb 19 14:18:45 salon sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 14:17 Feb 19 14:18:45 salon sudo[1676]: 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"