-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-06-20 17:17:28 CEST. -- Jun 20 17:16:00 volumiolcd nmbd[717]: [2025/06/20 17:16:00.593087, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 20 17:16:00 volumiolcd nmbd[717]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 20 17:16:00 volumiolcd systemd[1]: Started Samba NMB Daemon. Jun 20 17:16:00 volumiolcd ntpd[751]: Listen normally on 3 wlan0 192.168.1.130:123 Jun 20 17:16:00 volumiolcd ntpd[751]: new interface(s) found: waking up resolver Jun 20 17:16:00 volumiolcd nmbd[717]: [2025/06/20 17:16:00.622575, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 20 17:16:00 volumiolcd nmbd[717]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.130 for name WORKGROUP<1d>. Jun 20 17:16:00 volumiolcd nmbd[717]: This response was from IP 192.168.1.20, reporting an IP address of 192.168.1.20. Jun 20 17:16:00 volumiolcd systemd[1]: Starting Samba Winbind Daemon... Jun 20 17:16:00 volumiolcd winbindd[1031]: [2025/06/20 17:16:00.997774, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 20 17:16:00 volumiolcd winbindd[1031]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 20 17:16:01 volumiolcd winbindd[1031]: [2025/06/20 17:16:01.051307, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 20 17:16:01 volumiolcd systemd[1]: Started Samba Winbind Daemon. Jun 20 17:16:01 volumiolcd winbindd[1031]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 20 17:16:01 volumiolcd systemd[1]: Starting Samba SMB Daemon... Jun 20 17:16:01 volumiolcd smbd[1036]: [2025/06/20 17:16:01.731047, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 20 17:16:01 volumiolcd systemd[1]: Started Samba SMB Daemon. Jun 20 17:16:01 volumiolcd smbd[1036]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 20 17:16:01 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:16:01 volumiolcd volumio[1014]: info: ----- Volumio3 ---- Jun 20 17:16:01 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:16:01 volumiolcd volumio[1014]: info: ----- System startup ---- Jun 20 17:16:01 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:16:02 volumiolcd volumio-time-update[560]: volumio-time-update-util: Fetching time from Volumio... Jun 20 17:16:02 volumiolcd volumio-time-update[560]: volumio-time-update-util: Setting system time to: 2025-06-20 17:17:04 Jun 20 17:16:02 volumiolcd sudo[1057]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-20 17:17:04 Jun 20 17:16:02 volumiolcd sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:16:02 volumiolcd dbus-daemon[561]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1058 comm="timedatectl set-time 2025-06-20 17:17:04 ") Jun 20 17:16:02 volumiolcd systemd[1]: Starting Time & Date Service... Jun 20 17:16:03 volumiolcd dbus-daemon[561]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 20 17:16:03 volumiolcd systemd[1]: Started Time & Date Service. Jun 20 17:17:04 volumiolcd systemd-timedated[1059]: Changed local time to Fri Jun 20 17:17:04 2025 Jun 20 17:17:04 volumiolcd sudo[1057]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:04 volumiolcd volumio-time-update[560]: volumio-time-update-util: System time updated successfully. Jun 20 17:17:04 volumiolcd systemd[1]: Started Volumio Time Update Utility. Jun 20 17:17:04 volumiolcd systemd[1]: Reached target Multi-User System. Jun 20 17:17:04 volumiolcd systemd[1]: Reached target Graphical Interface. Jun 20 17:17:04 volumiolcd volumio[1014]: info: MYVOLUMIO Environment detected Jun 20 17:17:04 volumiolcd systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 20 17:17:04 volumiolcd systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 20 17:17:04 volumiolcd systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 20 17:17:04 volumiolcd systemd[1]: Startup finished in 14.280s (kernel) + 32.811s (userspace) = 47.091s. Jun 20 17:17:04 volumiolcd volumio[1014]: info: Plugin folders cleanup Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning into folder /volumio/app/plugins/ Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category audio_interface Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category miscellanea Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category music_service Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category plugins.json Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category system_controller Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category user_interface Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning into folder /data/plugins/ Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category audio_interface Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category music_service Jun 20 17:17:04 volumiolcd volumio[1014]: info: Scanning category user_interface Jun 20 17:17:04 volumiolcd volumio[1014]: info: Plugin folders cleanup completed Jun 20 17:17:04 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:17:04 volumiolcd volumio[1014]: info: ----- Core plugins startup ---- Jun 20 17:17:04 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:17:04 volumiolcd volumio[1014]: info: Loading plugins from folder /volumio/app/plugins/ Jun 20 17:17:04 volumiolcd volumio[1014]: info: Adding plugin upnp to MyMusic Plugins Jun 20 17:17:04 volumiolcd volumio[1014]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 20 17:17:04 volumiolcd volumio[1014]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 20 17:17:04 volumiolcd volumio[1014]: info: Loading plugins from folder /data/plugins/ Jun 20 17:17:04 volumiolcd volumio[1014]: info: Loading plugin "system"... Jun 20 17:17:04 volumiolcd volumio[1014]: info: Loading plugin "appearance"... Jun 20 17:17:05 volumiolcd systemd[1]: systemd-fsckd.service: Succeeded. Jun 20 17:17:05 volumiolcd volumio[1014]: info: Loading plugin "network"... Jun 20 17:17:05 volumiolcd volumio[1014]: info: Refreshing Cached IP Addresses Jun 20 17:17:05 volumiolcd sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 17:17:05 volumiolcd sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:05 volumiolcd sudo[1070]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:05 volumiolcd sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 17:17:05 volumiolcd sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:05 volumiolcd sudo[1072]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:05 volumiolcd volumio[1014]: info: Loading plugin "services"... Jun 20 17:17:05 volumiolcd volumio[1014]: info: Loading plugin "alsa_controller"... Jun 20 17:17:05 volumiolcd sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 17:17:05 volumiolcd sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:05 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 17:17:05 volumiolcd volumio[1014]: info: Loading plugin "wizard"... Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "networkfs"... Jun 20 17:17:06 volumiolcd volumio[1014]: info: Starting Udev Watcher for removable devices Jun 20 17:17:06 volumiolcd sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.20/Storage/Muzyka /mnt/NAS/pve Jun 20 17:17:06 volumiolcd sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:06 volumiolcd volumio[1014]: info: Ignoring mount for partition: boot Jun 20 17:17:06 volumiolcd volumio[1014]: info: Ignoring mount for partition: volumio Jun 20 17:17:06 volumiolcd volumio[1014]: info: Ignoring mount for partition: volumio_data Jun 20 17:17:06 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "volumio_command_line_client"... Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "upnp"... Jun 20 17:17:06 volumiolcd volumio[1014]: info: [1750432626173] Starting Upmpd Daemon Jun 20 17:17:06 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "my_music"... Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "mpd"... Jun 20 17:17:06 volumiolcd kernel: Key type cifs.spnego registered Jun 20 17:17:06 volumiolcd kernel: Key type cifs.idmap registered Jun 20 17:17:06 volumiolcd 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. Jun 20 17:17:06 volumiolcd kernel: CIFS: Attempting to mount //192.168.1.20/Storage/Muzyka Jun 20 17:17:06 volumiolcd kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 17:17:06 volumiolcd volumio[1014]: info: Loading plugin "upnp_browser"... Jun 20 17:17:06 volumiolcd sudo[1098]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "alarm-clock"... Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "airplay_emulation"... Jun 20 17:17:07 volumiolcd volumio[1014]: info: Starting Shairport Sync Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "last_100"... Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "webradio"... Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "i2s_dacs"... Jun 20 17:17:07 volumiolcd volumio[1014]: info: I2S DAC not set, start Auto-detection Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "volumiodiscovery"... Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** For more information see Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 17:17:07 volumiolcd volumio[1014]: *** WARNING *** For more information see Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** For more information see Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 17:17:07 volumiolcd node[1014]: *** WARNING *** For more information see Jun 20 17:17:07 volumiolcd volumio[1014]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 20 17:17:07 volumiolcd volumio[1014]: info: Discovery: Started advertising with name: VolumioLCD Jun 20 17:17:07 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 17:17:07 volumiolcd volumio[1014]: info: Loading plugin "spop"... Jun 20 17:17:08 volumiolcd sh[468]: timed out Jun 20 17:17:08 volumiolcd dhcpcd[536]: timed out Jun 20 17:17:08 volumiolcd sh[468]: dhcpcd exited Jun 20 17:17:08 volumiolcd dhcpcd[536]: dhcpcd exited Jun 20 17:17:08 volumiolcd sh[468]: ifup: failed to bring up eth0 Jun 20 17:17:08 volumiolcd systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 20 17:17:08 volumiolcd systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 20 17:17:09 volumiolcd sudo[1081]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "outputs"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "albumart"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Plugin example_plugin is not enabled Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "inputs"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "updater_comm"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Plugin mpdemulation is not enabled Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "rest_api"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "websocket"... Jun 20 17:17:09 volumiolcd volumio[1014]: info: Starting Socket.io Server version 2.3.0 Jun 20 17:17:09 volumiolcd volumio[1014]: info: Loading plugin "fusiondsp"... Jun 20 17:17:09 volumiolcd volumio[1014]: Forking 3 albumart workers Jun 20 17:17:10 volumiolcd volumio[1014]: info: Applying required configuration parameters for plugin fusiondsp Jun 20 17:17:10 volumiolcd systemd[1]: systemd-hostnamed.service: Succeeded. Jun 20 17:17:10 volumiolcd volumio-remote-updater[555]: [2025-06-20 17:17:10] [connect] Successful connection Jun 20 17:17:10 volumiolcd volumio[1014]: info: Loading plugin "touch_display"... Jun 20 17:17:10 volumiolcd volumio[1014]: Starting albumart workers Jun 20 17:17:10 volumiolcd volumio[1014]: Starting albumart workers Jun 20 17:17:10 volumiolcd volumio[1014]: Starting albumart workers Jun 20 17:17:11 volumiolcd volumio[1014]: info: Applying required configuration parameters for plugin touch_display Jun 20 17:17:11 volumiolcd volumio[1014]: info: Loading i18n strings for locale pl Jun 20 17:17:11 volumiolcd volumio[1014]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 20 17:17:11 volumiolcd volumio[1014]: Updating browse sources language Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::initPlayerControls Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 17:17:11 volumiolcd volumio[1014]: Express server listening on port 3000 Jun 20 17:17:11 volumiolcd volumio[1014]: [Metrics] WebUI: 10s 333.73ms Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreStateMachine::resetVolumioState Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreStateMachine::getcurrentVolume Jun 20 17:17:11 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: Volumio Network Manager: Network status updated: 2 Jun 20 17:17:12 volumiolcd volumio[1014]: verbose: New Socket.io Connection to 192.168.1.130:3000 from 192.168.1.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 20 17:17:12 volumiolcd volumio[1014]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Linux; Android 13; SM-M526BR Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 20 17:17:12 volumiolcd volumio[1014]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Linux; Android 13; SM-M526BR Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 20 17:17:12 volumiolcd volumio-remote-updater[555]: [2025-06-20 17:17:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750432630 101 Jun 20 17:17:12 volumiolcd volumio[1014]: 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: 4 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 17:17:12 volumiolcd volumio[1014]: info: Discovery: Getting this device information Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 17:17:12 volumiolcd volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 20 17:17:12 volumiolcd volumio[1014]: info: Reloading queue from file Jun 20 17:17:12 volumiolcd volumio[1014]: info: VolumeController:: Volume=56 Mute =false Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::pushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::updateTrackBlock Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrackBlock Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 17:17:12 volumiolcd volumio[1014]: info: Setting Device type: Raspberry PI Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::setRepeat null single undefined Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::pushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::setRandom null Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::pushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 20 17:17:12 volumiolcd volumio[1014]: verbose: New Socket.io Connection to 192.168.1.130:3000 from 192.168.1.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 20 17:17:12 volumiolcd volumio[1014]: info: Discovery: adding f301ae42-df64-4cbd-bb05-417d06cd929e Jun 20 17:17:12 volumiolcd volumio[1014]: info: Discovery: Found device VolumioLCD Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: VolumeController:: Volume=56 Mute =false Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreStateMachine::pushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 20 17:17:12 volumiolcd volumio[1014]: info: Completed loading Core Plugins Jun 20 17:17:12 volumiolcd volumio[1014]: info: Preparing to generate the ALSA configuration file Jun 20 17:17:12 volumiolcd volumio[1014]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 17:17:12 volumiolcd volumio[1014]: info: Reading ALSA contributions from plugins. Jun 20 17:17:12 volumiolcd volumio[1014]: info: Asound.conf file unchanged, so no further update is needed Jun 20 17:17:12 volumiolcd volumio[1014]: info: Output device has changed, restarting MPD Jun 20 17:17:12 volumiolcd volumio[1014]: info: Output device has changed, restarting Shairport Sync Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:12 volumiolcd sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 17:17:12 volumiolcd sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:12 volumiolcd sudo[1207]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:12 volumiolcd sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 17:17:12 volumiolcd sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:12 volumiolcd systemd[1]: Stopping Music Player Daemon... Jun 20 17:17:12 volumiolcd volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: ___________ START PLUGINS ___________ Jun 20 17:17:12 volumiolcd volumio[1014]: info: ControllerMpd::onStart: Initializing MPD Jun 20 17:17:12 volumiolcd volumio[1014]: info: Creating MPD Configuration file Jun 20 17:17:12 volumiolcd sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 17:17:12 volumiolcd sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:12 volumiolcd volumio[1014]: info: [1750432632727] CoreMusicLibrary::Adding element Serwery Mediów Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:12 volumiolcd sudo[1216]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:12 volumiolcd sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 17:17:12 volumiolcd sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 17:17:12 volumiolcd volumio[1014]: info: [1750432632842] CoreMusicLibrary::Adding element Last_100 Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 17:17:12 volumiolcd volumio[1014]: info: [1750432632846] CoreMusicLibrary::Adding element Webradio Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 17:17:12 volumiolcd volumio[1014]: info: Initializing BBC Radios Jun 20 17:17:12 volumiolcd systemd[1]: mpd.service: Succeeded. Jun 20 17:17:12 volumiolcd systemd[1]: Stopped Music Player Daemon. Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:12 volumiolcd volumio[1014]: info: Creating Spotify config file Jun 20 17:17:12 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:12 volumiolcd systemd[1]: Starting Music Player Daemon... Jun 20 17:17:13 volumiolcd volumio[1014]: info: Loading i18n strings for locale pl Jun 20 17:17:13 volumiolcd volumio[1014]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: FusionDsp - mixtype--------------------- Hardware Jun 20 17:17:13 volumiolcd volumio[1014]: info: Loading i18n strings for locale pl Jun 20 17:17:13 volumiolcd volumio[1014]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 17:17:13 volumiolcd volumio[1014]: info: Volumio Calling Home Jun 20 17:17:13 volumiolcd sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 17:17:13 volumiolcd sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd sudo[1237]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:13 volumiolcd volumio[1014]: info: Preparing to generate the ALSA configuration file Jun 20 17:17:13 volumiolcd sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 20 17:17:13 volumiolcd sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 20 17:17:13 volumiolcd sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 20 17:17:13 volumiolcd sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd volumio[1014]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 17:17:13 volumiolcd volumio[1014]: info: Reading ALSA contributions from plugins. Jun 20 17:17:13 volumiolcd volumio[1014]: info: touch_display: Backlight interface detected. Jun 20 17:17:13 volumiolcd volumio[1014]: info: MPD Permissions set Jun 20 17:17:13 volumiolcd systemd[1]: Reloading. Jun 20 17:17:13 volumiolcd volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 20 17:17:13 volumiolcd volumio[1014]: info: Spotify config file written Jun 20 17:17:13 volumiolcd volumio[1014]: info: Volumio called home Jun 20 17:17:13 volumiolcd sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 20 17:17:13 volumiolcd sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: No need to fix Spotify hosts Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Jun 20 17:17:13 volumiolcd sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd volumio[1014]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jun 20 17:17:13 volumiolcd sudo[1277]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:13 volumiolcd sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 20 17:17:13 volumiolcd sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd 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. Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:13 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:13 volumiolcd sudo[1279]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:13 volumiolcd volumio[1014]: info: Starting Shairport Sync Jun 20 17:17:13 volumiolcd volumio[1014]: info: Starting Shairport Sync Jun 20 17:17:13 volumiolcd volumio[1014]: info: Starting Shairport Sync Jun 20 17:17:13 volumiolcd sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 17:17:13 volumiolcd sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 17:17:13 volumiolcd sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 17:17:13 volumiolcd sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:13 volumiolcd volumio[1014]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 20 17:17:13 volumiolcd volumio[1014]: info: touch_display: File permissions for backlight brightness control set. Jun 20 17:17:13 volumiolcd volumio[1014]: info: Asound.conf file unchanged, so no further update is needed Jun 20 17:17:13 volumiolcd volumio[1014]: info: Output device has changed, restarting MPD Jun 20 17:17:13 volumiolcd volumio[1014]: info: Output device has changed, restarting Shairport Sync Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:13 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:13 volumiolcd sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 17:17:13 volumiolcd sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:14 volumiolcd sudo[1292]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:14 volumiolcd sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 17:17:14 volumiolcd sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:14 volumiolcd volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 17:17:14 volumiolcd volumio[1014]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 20 17:17:14 volumiolcd volumio[1014]: SPOTIFY: BQAA4BBFqI9d758f4Vf0fhszqaDgv_37OX0ayoM4xWWMiS6JdpeGrj13f0KPquj7jte1ehX0i04hIdDjq5G3zCfgoIYtmH7xtfJtgSt9W7CRa7ej3l7emXov6TUjZKne6ZyVaOtYRKRUu8vEGi3Blj_osidlB4VnxtJCQ6Q_p_ddprofxS4NxJF23LDhq0f6jyFFqO7wfObOqvevGniAzGfzj_FRm3ZxxoVc3ReLXfXZWii41ODOkLdmBMYPGsq950w5M2sZgxxHLyYI-aAfok72Esna3GI7E1_qCXsTEb1yR4MuAbU Jun 20 17:17:14 volumiolcd volumio[1014]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 20 17:17:14 volumiolcd volumio[1014]: info: New Spotify access token = BQAA4BBFqI9d758f4Vf0fhszqaDgv_37OX0ayoM4xWWMiS6JdpeGrj13f0KPquj7jte1ehX0i04hIdDjq5G3zCfgoIYtmH7xtfJtgSt9W7CRa7ej3l7emXov6TUjZKne6ZyVaOtYRKRUu8vEGi3Blj_osidlB4VnxtJCQ6Q_p_ddprofxS4NxJF23LDhq0f6jyFFqO7wfObOqvevGniAzGfzj_FRm3ZxxoVc3ReLXfXZWii41ODOkLdmBMYPGsq950w5M2sZgxxHLyYI-aAfok72Esna3GI7E1_qCXsTEb1yR4MuAbU Jun 20 17:17:14 volumiolcd volumio[1014]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 20 17:17:14 volumiolcd volumio[1014]: info: MPD Permissions set Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:14 volumiolcd volumio[1014]: info: Starting Shairport Sync Jun 20 17:17:14 volumiolcd sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 17:17:14 volumiolcd sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:14 volumiolcd sudo[1249]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:14 volumiolcd volumio[1014]: info: touch_display: systemctl daemon-reload succeeded. Jun 20 17:17:14 volumiolcd sudo[1244]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:14 volumiolcd volumio[1014]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 20 17:17:14 volumiolcd systemd[1]: Reloading. Jun 20 17:17:14 volumiolcd sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 20 17:17:14 volumiolcd sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:14 volumiolcd volumio[1014]: SPOTIFY: User informations: {"country":"PL","display_name":"ciach.sylwester","email":"ciach.sylwester@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31fiqb572d5ajt2vhuo7nuimpxie"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31fiqb572d5ajt2vhuo7nuimpxie","id":"31fiqb572d5ajt2vhuo7nuimpxie","images":[],"product":"premium","type":"user","uri":"spotify:user:31fiqb572d5ajt2vhuo7nuimpxie"} Jun 20 17:17:14 volumiolcd volumio[1014]: info: Spotify Successfully logged in Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 17:17:14 volumiolcd volumio[1014]: info: [1750432634400] CoreMusicLibrary::Adding element Spotify Jun 20 17:17:14 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:14 volumiolcd volumio[1014]: Cannot find translation for source Spotify Jun 20 17:17:14 volumiolcd 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. Jun 20 17:17:14 volumiolcd mpd[1241]: Jun 20 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 17:17:15 volumiolcd volumio[1014]: info: camilladsp spawned new process with pid 1332, instance 1, run: true Jun 20 17:17:15 volumiolcd volumio[1014]: info: camilladsp service started and running in background, instance 1 Jun 20 17:17:15 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 17:17:15 volumiolcd systemd[1]: Started Music Player Daemon. Jun 20 17:17:15 volumiolcd sudo[1246]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1218]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1209]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd volumio[1014]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jun 20 17:17:15 volumiolcd volumio[1014]: info: FusionDsp loaded Jun 20 17:17:15 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 17:17:15 volumiolcd sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 20 17:17:15 volumiolcd sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:15 volumiolcd volumio[1014]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 20 17:17:15 volumiolcd volumio[1014]: info: Adding Signal Path Element [object Object] Jun 20 17:17:15 volumiolcd volumio[1014]: info: Adding fusiondspeq DSP Signal Path Element Jun 20 17:17:15 volumiolcd volumio[1014]: info: FusionDsp - ---- installed callbackRead Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 20 17:17:15 volumiolcd volumio[1014]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 20 17:17:15 volumiolcd volumio[1014]: info: MPD running with PID1241 Jun 20 17:17:15 volumiolcd volumio[1014]: ,establishing connection Jun 20 17:17:15 volumiolcd volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Jun 20 17:17:15 volumiolcd volumio[1014]: assert.ok(self.idling) Jun 20 17:17:15 volumiolcd volumio[1014]: error: The expression evaluated to a falsy value: Jun 20 17:17:15 volumiolcd volumio[1014]: assert.ok(self.idling) Jun 20 17:17:15 volumiolcd volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Jun 20 17:17:15 volumiolcd volumio[1014]: assert.ok(self.idling) Jun 20 17:17:15 volumiolcd volumio[1014]: error: The expression evaluated to a falsy value: Jun 20 17:17:15 volumiolcd volumio[1014]: assert.ok(self.idling) Jun 20 17:17:15 volumiolcd systemd[1]: Started FusionDsp Daemon. Jun 20 17:17:15 volumiolcd volumio[1014]: error: FusionDsp - WebSocket error: [object Object] Jun 20 17:17:15 volumiolcd volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 20 17:17:15 volumiolcd systemd[1]: Started Volumio Kiosk. Jun 20 17:17:15 volumiolcd systemd[1]: Stopping Music Player Daemon... Jun 20 17:17:15 volumiolcd sudo[1309]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 20 17:17:15 volumiolcd systemd[1]: Started go-librespot Daemon. Jun 20 17:17:15 volumiolcd sudo[1253]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd volumio[1014]: error: updateQueue error: null Jun 20 17:17:15 volumiolcd go-librespot[1348]: Librespot-go daemon starting... Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: Volumio Kiosk started. Jun 20 17:17:15 volumiolcd systemd[1]: shairport-sync.service: Succeeded. Jun 20 17:17:15 volumiolcd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 20 17:17:15 volumiolcd systemd[1]: mpd.service: Succeeded. Jun 20 17:17:15 volumiolcd systemd[1]: Stopped Music Player Daemon. Jun 20 17:17:15 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:15 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:15 volumiolcd volumio[1014]: info: Completed starting Core Plugins Jun 20 17:17:15 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:17:15 volumiolcd volumio[1014]: info: ----- MyVolumio plugins startup ---- Jun 20 17:17:15 volumiolcd volumio[1014]: info: ------------------------------------------- Jun 20 17:17:15 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 20 17:17:15 volumiolcd systemd[1]: Starting Music Player Daemon... Jun 20 17:17:15 volumiolcd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 20 17:17:15 volumiolcd sudo[1283]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1289]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1285]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:15 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:15 volumiolcd volumio[1014]: info: Shairport-Sync Started Jun 20 17:17:15 volumiolcd volumio[1014]: Error adding Membership: Error: addMembership EINVAL Jun 20 17:17:15 volumiolcd volumio[1014]: info: Shairport-Sync Started Jun 20 17:17:15 volumiolcd volumio[1014]: info: Shairport-Sync Started Jun 20 17:17:15 volumiolcd volumio[1014]: info: Shairport-Sync Started Jun 20 17:17:15 volumiolcd sudo[1354]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 17:17:15 volumiolcd sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:15 volumiolcd sudo[1354]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: X display number found: Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:15 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:16 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:16 volumiolcd volumio[1014]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Linux; Android 13; SM-M526BR Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:16 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 17:17:16 volumiolcd volumio[1014]: info: Received Get System Info Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 17:17:16 volumiolcd volumio[1014]: info: Discovery: Getting this device information Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:16 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:16 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:16 volumiolcd volumio[1014]: info: Listing playlists Jun 20 17:17:16 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:16 volumiolcd sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 17:17:16 volumiolcd sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:16 volumiolcd sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 17:17:16 volumiolcd sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:16 volumiolcd sudo[1410]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 17:17:16 volumiolcd sudo[1408]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 17:17:16 volumiolcd volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 17:17:16 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:16 volumiolcd startx[1343]: X.Org X Server 1.20.4 Jun 20 17:17:16 volumiolcd startx[1343]: X Protocol Version 11, Revision 0 Jun 20 17:17:16 volumiolcd startx[1343]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 20 17:17:16 volumiolcd startx[1343]: Current Operating System: Linux volumiolcd 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jun 20 17:17:16 volumiolcd startx[1343]: 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=800 bcm2708_fb.fbheight=480 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=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a 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 Jun 20 17:17:16 volumiolcd startx[1343]: Build Date: 04 April 2023 07:50:56AM Jun 20 17:17:16 volumiolcd startx[1343]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jun 20 17:17:16 volumiolcd startx[1343]: Current version of pixman: 0.36.0 Jun 20 17:17:16 volumiolcd startx[1343]: Before reporting problems, check http://wiki.x.org Jun 20 17:17:16 volumiolcd startx[1343]: to make sure that you have the latest version. Jun 20 17:17:16 volumiolcd startx[1343]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 20 17:17:16 volumiolcd startx[1343]: (++) from command line, (!!) notice, (II) informational, Jun 20 17:17:16 volumiolcd startx[1343]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 20 17:17:16 volumiolcd startx[1343]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jun 20 17:17:16 2025 Jun 20 17:17:16 volumiolcd startx[1343]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 20 17:17:16 volumiolcd startx[1343]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:16+02:00" level=info msg="generated new device id: b59ccecd9d23c70bf8c0cc362264ca848b84cf6c" Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:17+02:00" level=debug msg="stored credentials found for 31fiqb572d5ajt2vhuo7nuimpxie" Jun 20 17:17:17 volumiolcd mpd[1371]: Jun 20 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 17:17:17 volumiolcd sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 20 17:17:17 volumiolcd sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:17 volumiolcd systemd[1]: Started UPnP Renderer front-end to MPD. Jun 20 17:17:17 volumiolcd systemd[1]: Started Music Player Daemon. Jun 20 17:17:17 volumiolcd sudo[1419]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:17 volumiolcd sudo[1294]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:17 volumiolcd volumio[1014]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 20 17:17:17 volumiolcd volumio[1014]: info: Upmpdcli Daemon Started Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:17+02: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-gew1.spotify.com:80]" Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 20 17:17:17 volumiolcd volumio[1014]: info: touch_display: X display number found: 0 Jun 20 17:17:17 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:17+02:00" level=debug msg="zeroconf server listening on port 37949" Jun 20 17:17:18 volumiolcd volumio[1014]: error: updateQueue error: null Jun 20 17:17:18 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:18+02:00" level=debug msg="obtained new client token: AABpyXr2+ikl10C6oQHndreV6YuGg6PD4OfK+5irXbUX45Nb23a/I/6//T3tpbbk+igxErLaUYMAjR84ZADzp2J3SNbNGZuJJqMcFJDvxXkdoUOJk83OmBymLVoRIep3KNcjsBMP6TaUJalEjFJnhZsimAkhCAhofP9TBgElCYUT9NcK7VhMlk49NRvHRkwdz3GaApQiC5h+bkH6WVtXIVHQKtNIy+ubSeUQAwzO1DmWQWlDh87lUimEPaGE8g==" Jun 20 17:17:18 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:18+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jun 20 17:17:18 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:18+02:00" level=debug msg="completed keyexchange" Jun 20 17:17:18 volumiolcd volumio[1421]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 20 17:17:18 volumiolcd volumio[1014]: info: go-librespot daemon successfully initialized Jun 20 17:17:18 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:18+02:00" level=debug msg="completed challenge" Jun 20 17:17:18 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:18+02:00" level=debug msg="authenticated as 31fiqb572d5ajt2vhuo7nuimpxie" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="authenticated as 31fiqb572d5ajt2vhuo7nuimpxie" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="dealer connection opened" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="initializing zeroconf session, username: 31fiqb572d5ajt2vhuo7nuimpxie" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="autoplay enabled: false" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="received connection id: ZWQ1YjIyZGItYmZmMy00YzJlLThkNWYtNmQ2NDYwMGE3MTI3K2RlYWxlcit0Y3A6Ly8wYWNhNTg5NS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArN0RBMDRCRTFGQTg2NEU3MDlFQ0I3Mjg1QjkzNDJGNkI1QTg0MTM2MzdDQ0IxRkUwNzY5MzU5NDM4RkY2OThFRg==" Jun 20 17:17:19 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:19+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 20 17:17:20 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:20 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:20 volumiolcd volumio[1014]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 20 17:17:20 volumiolcd volumio[1014]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 20 17:17:21 volumiolcd volumio[1014]: Cannot compose Albumart path Jun 20 17:17:21 volumiolcd volumio[1014]: info: Initializing connection to go-librespot Websocket Jun 20 17:17:21 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:21+02:00" level=debug msg="new websocket client" Jun 20 17:17:21 volumiolcd volumio[1014]: info: Connection to go-librespot Websocket established Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin bluetooth to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin multiroom to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin metavolumio to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin cd_controller to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 20 17:17:24 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="handling transfer player command from db81d13c8611f5edb595e54c2565bab423b4c599" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:5BzVrRn3W6Y6ZfHjc0XWuY" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=trace msg="fetched new page 0 with 65 items (list: 65)" uri="spotify:playlist:5BzVrRn3W6Y6ZfHjc0XWuY" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="loading track (paused: true, position: 118800ms)" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=trace msg="emitting websocket event: will_play" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1140" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="selected format OGG_VORBIS_320 (dd4d7143cf30505e33892a23a4c315a512462f2b)" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:24 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:24+02:00" level=debug msg="requested aes key for file dd4d7143cf30505e33892a23a4c315a512462f2b, gid: 3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched first chunk of 24, total size is 12068824 bytes" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd volumio[1421]: ...........................................................++++ Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 11/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 10/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=trace msg="seek to 118800ms (diff: 64ms, samples: 5239080, bytes: 5487843)" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 13/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="fetched chunk 12/23, size: 524288" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="created new output device" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=info msg="loaded track \"Law of Attraction\" (paused: true, position: 118800ms, duration: 269250ms, prefetched: false)" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=trace msg="emitting websocket event: metadata" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=trace msg="emitting websocket event: active" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="sending successful reply for dealer request" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 20 17:17:25 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:25+02:00" level=trace msg="emitting websocket event: paused" Jun 20 17:17:25 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 20 17:17:25 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 20 17:17:25 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:25 volumiolcd volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 17:17:25 volumiolcd volumio[1014]: info: Starting MyVolumio Remote Streaming Endpoints Jun 20 17:17:25 volumiolcd volumio[1014]: info: MyVolumio login type: Token Jun 20 17:17:26 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 20 17:17:26 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 20 17:17:27 volumiolcd volumio[1014]: info: Starting Streaming Service Transparent Proxy Jun 20 17:17:27 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 20 17:17:27 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 20 17:17:27 volumiolcd volumio[1014]: info: Streaming services startup Jun 20 17:17:27 volumiolcd volumio[1014]: info: Starting Streaming Daemon Jun 20 17:17:27 volumiolcd volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 20 17:17:27 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:27+02:00" level=debug msg="handling resume player command from db81d13c8611f5edb595e54c2565bab423b4c599" Jun 20 17:17:27 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:27+02:00" level=trace msg="seek to 118800ms (diff: 64ms, samples: 5239080, bytes: 5487843)" uri="spotify:track:3h4XNmeol2gHd288mRQZ1i" Jun 20 17:17:27 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:27+02:00" level=debug msg="resume track at 118736ms" Jun 20 17:17:27 volumiolcd volumio[1014]: info: Getting Spotify volume Jun 20 17:17:27 volumiolcd go-librespot[1348]: time="2025-06-20T17:17:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 20 17:17:27 volumiolcd volumio[1014]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3h4XNmeol2gHd288mRQZ1i","play_origin":"driving-jumpstart"}} Jun 20 17:17:27 volumiolcd volumio[1014]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3h4XNmeol2gHd288mRQZ1i","name":"Law of Attraction","artist_names":["Dezza"],"album_name":"In Tents/Law Of Attraction","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028db78a2d6e07718976e9f486","position":118800,"duration":269250,"release_date":"year:2018 month:5 day:11","track_number":2,"disc_number":1}} Jun 20 17:17:27 volumiolcd volumio[1014]: SPOTIFY: received: {"type":"active","data":null} Jun 20 17:17:27 volumiolcd volumio[1014]: info: Aligning Spotify Volume to Volumio Volume Jun 20 17:17:27 volumiolcd volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 20 17:17:27 volumiolcd volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 20 17:17:27 volumiolcd volumio[1014]: info: Setting Spotify Volume from Volumio: 56 Jun 20 17:17:27 volumiolcd volumio[1014]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3h4XNmeol2gHd288mRQZ1i","play_origin":"driving-jumpstart"}} Jun 20 17:17:27 volumiolcd volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 17:17:27 volumiolcd sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 20 17:17:27 volumiolcd sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:27 volumiolcd sudo[1560]: pam_unix(sudo:session): session closed for user root Jun 20 17:17:27 volumiolcd volumio[1014]: TypeError: Cannot read property 'service' of undefined Jun 20 17:17:27 volumiolcd volumio[1014]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Jun 20 17:17:27 volumiolcd volumio[1014]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:271:18) Jun 20 17:17:27 volumiolcd volumio[1014]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Jun 20 17:17:27 volumiolcd volumio[1014]: at WebSocket.emit (events.js:315:20) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.emit (events.js:315:20) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 20 17:17:27 volumiolcd volumio[1014]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 20 17:17:27 volumiolcd volumio[1014]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 20 17:17:27 volumiolcd volumio[1014]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jun 20 17:17:27 volumiolcd volumio[1014]: at Socket.emit (events.js:315:20) Jun 20 17:17:27 volumiolcd volumio[1014]: at addChunk (internal/streams/readable.js:309:12) Jun 20 17:17:27 volumiolcd volumio[1014]: at readableAddChunk (internal/streams/readable.js:284:9) Jun 20 17:17:27 volumiolcd volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 17:17:28 volumiolcd sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-20 17:16 Jun 20 17:17:28 volumiolcd sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 17:17:28 volumiolcd volumio[1014]: STREAMING PROXY: Starting server on port 3245 Jun 20 17:17:28 volumiolcd volumio[1014]: Node JS runtime: 14 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"