Dec 16 20:50:43 tandberg ntpd[995]: CLOCK: time stepped by 782417.579162 Dec 16 20:50:43 tandberg ntpd[995]: CLOCK: time changed from 2025-12-07 to 2025-12-16 Dec 16 20:50:43 tandberg ntpd[995]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 16 20:50:43 tandberg volumio[1231]: info: Plugin folders cleanup Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning into folder /volumio/app/plugins/ Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category audio_interface Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category miscellanea Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category music_service Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category plugins.json Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category system_controller Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category user_interface Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning into folder /data/plugins/ Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category music_service Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category system_controller Dec 16 20:50:43 tandberg volumio[1231]: info: Scanning category system_hardware Dec 16 20:50:43 tandberg volumio[1231]: info: Plugin folders cleanup completed Dec 16 20:50:43 tandberg volumio[1231]: info: ------------------------------------------- Dec 16 20:50:43 tandberg volumio[1231]: info: ----- Core plugins startup ---- Dec 16 20:50:43 tandberg volumio[1231]: info: ------------------------------------------- Dec 16 20:50:43 tandberg volumio[1231]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 20:50:43 tandberg volumio[1231]: info: Adding plugin upnp to MyMusic Plugins Dec 16 20:50:43 tandberg systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 16 20:50:43 tandberg systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 16 20:50:43 tandberg volumio[1231]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 20:50:43 tandberg volumio[1231]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 20:50:43 tandberg systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 16 20:50:43 tandberg systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 16 20:50:43 tandberg systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 16 20:50:43 tandberg volumio[1231]: info: Loading plugins from folder /data/plugins/ Dec 16 20:50:43 tandberg systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 16 20:50:43 tandberg volumio[1231]: info: Loading plugin "system"... Dec 16 20:50:43 tandberg volumio[1231]: info: Loading plugin "appearance"... Dec 16 20:50:43 tandberg systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 16 20:50:43 tandberg systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 16 20:50:44 tandberg volumio[1231]: info: Loading plugin "network"... Dec 16 20:50:44 tandberg volumio[1231]: info: Refreshing Cached IP Addresses Dec 16 20:50:44 tandberg sudo[1322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 20:50:44 tandberg sudo[1324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 20:50:44 tandberg sudo[1324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:50:44 tandberg volumio[1231]: info: Loading plugin "services"... Dec 16 20:50:44 tandberg sudo[1322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:50:44 tandberg sudo[1324]: pam_unix(sudo:session): session closed for user root Dec 16 20:50:44 tandberg volumio[1231]: info: Loading plugin "alsa_controller"... Dec 16 20:50:44 tandberg sudo[1322]: pam_unix(sudo:session): session closed for user root Dec 16 20:50:44 tandberg sudo[1333]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 20:50:44 tandberg sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:50:45 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "wizard"... Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "networkfs"... Dec 16 20:50:45 tandberg volumio[1231]: info: Starting Udev Watcher for removable devices Dec 16 20:50:45 tandberg volumio[1231]: info: Ignoring mount for partition: boot Dec 16 20:50:45 tandberg volumio[1231]: info: Ignoring mount for partition: volumio Dec 16 20:50:45 tandberg volumio[1231]: info: Ignoring mount for partition: volumio_data Dec 16 20:50:45 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "volumio_command_line_client"... Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "upnp"... Dec 16 20:50:45 tandberg sudo[1353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.60/Lenovo/Music /mnt/NAS/PC Dec 16 20:50:45 tandberg sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:50:45 tandberg volumio[1231]: info: [1765914645141] Starting Upmpd Daemon Dec 16 20:50:45 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "my_music"... Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "mpd"... Dec 16 20:50:45 tandberg kernel: netfs: FS-Cache loaded Dec 16 20:50:45 tandberg kernel: Key type cifs.spnego registered Dec 16 20:50:45 tandberg kernel: Key type cifs.idmap registered Dec 16 20:50:45 tandberg 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. Dec 16 20:50:45 tandberg kernel: CIFS: Attempting to mount //192.168.1.60/Lenovo/Music Dec 16 20:50:45 tandberg volumio[1231]: info: Loading plugin "upnp_browser"... Dec 16 20:50:46 tandberg volumio[1231]: info: Starting UPNP Browser Dec 16 20:50:46 tandberg volumio[1231]: info: Loading plugin "alarm-clock"... Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "airplay_emulation"... Dec 16 20:50:47 tandberg volumio[1231]: info: Starting Shairport Sync Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "last_100"... Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "webradio"... Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "i2s_dacs"... Dec 16 20:50:47 tandberg volumio[1231]: info: I2S DAC not set, start Auto-detection Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "volumiodiscovery"... Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** For more information see Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** For more information see Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 20:50:47 tandberg volumio[1231]: *** WARNING *** For more information see Dec 16 20:50:47 tandberg node[1231]: *** WARNING *** For more information see Dec 16 20:50:47 tandberg volumio[1231]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 20:50:47 tandberg volumio[1231]: info: Discovery: Started advertising with name: Tandberg Dec 16 20:50:47 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 20:50:47 tandberg volumio[1231]: info: Loading plugin "spop"... Dec 16 20:50:47 tandberg dhcpcd[709]: timed out Dec 16 20:50:47 tandberg sh[688]: timed out Dec 16 20:50:47 tandberg sh[570]: ifup: failed to bring up eth0 Dec 16 20:50:47 tandberg systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 16 20:50:47 tandberg systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 16 20:50:48 tandberg sudo[1333]: pam_unix(sudo:session): session closed for user root Dec 16 20:50:49 tandberg volumio[1231]: info: Plugin ytcr is not enabled Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "outputs"... Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "albumart"... Dec 16 20:50:49 tandberg volumio[1231]: info: Plugin example_plugin is not enabled Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "inputs"... Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "updater_comm"... Dec 16 20:50:49 tandberg volumio[1231]: info: Plugin mpdemulation is not enabled Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "rest_api"... Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "websocket"... Dec 16 20:50:49 tandberg volumio[1231]: info: Starting Socket.io Server version 1.7.4 Dec 16 20:50:49 tandberg volumio[1231]: info: Loading plugin "controradio"... Dec 16 20:50:49 tandberg systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 16 20:50:49 tandberg systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 20:50:49 tandberg systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 20:50:49 tandberg upmpdcli[1384]: Could not open config: /tmp/upmpdcli.conf Dec 16 20:50:49 tandberg systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 16 20:50:49 tandberg systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 16 20:50:49 tandberg systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 16 20:50:50 tandberg volumio[1374]: Forking 3 albumart workers Dec 16 20:50:51 tandberg kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 16 20:50:51 tandberg kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 16 20:50:51 tandberg sudo[1353]: pam_unix(sudo:session): session closed for user root Dec 16 20:50:54 tandberg volumio[1231]: info: Applying required configuration parameters for plugin controradio Dec 16 20:50:55 tandberg volumio[1231]: info: Loading plugin "minidlna"... Dec 16 20:50:56 tandberg volumio[1390]: Starting albumart workers Dec 16 20:50:56 tandberg volumio[1388]: Starting albumart workers Dec 16 20:50:56 tandberg volumio-remote-updater[758]: [2025-12-16 20:50:56] [connect] Successful connection Dec 16 20:50:56 tandberg volumio[1231]: info: Applying required configuration parameters for plugin minidlna Dec 16 20:50:56 tandberg volumio[1389]: Starting albumart workers Dec 16 20:50:57 tandberg systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 16 20:50:57 tandberg volumio[1231]: info: Loading plugin "backup_restore"... Dec 16 20:50:58 tandberg systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 16 20:50:58 tandberg systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 16 20:50:59 tandberg volumio[1231]: info: Applying required configuration parameters for plugin backup_restore Dec 16 20:50:59 tandberg volumio[1231]: info: Loading plugin "Bluetoothremote"... Dec 16 20:51:00 tandberg volumio[1231]: info: Applying required configuration parameters for plugin Bluetoothremote Dec 16 20:51:00 tandberg volumio[1231]: info: Loading i18n strings for locale en Dec 16 20:51:00 tandberg volumio[1231]: Updating browse sources language Dec 16 20:51:00 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::initPlayerControls Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 20:51:01 tandberg volumio[1231]: Express server listening on port 3000 Dec 16 20:51:01 tandberg volumio[1231]: [Metrics] WebUI: 20s 692.23ms Dec 16 20:51:01 tandberg volumio[1231]: info: CoreStateMachine::resetVolumioState Dec 16 20:51:01 tandberg volumio[1231]: info: CoreStateMachine::getcurrentVolume Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:01 tandberg sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 20:51:01 tandberg sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:01 tandberg sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 20:51:01 tandberg sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:01 tandberg sudo[1486]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:01 tandberg sudo[1487]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:01 tandberg volumio[1231]: info: Volumio Network Manager: Network status updated: 2 Dec 16 20:51:01 tandberg volumio[1231]: info: Cannot mount NAS PC at system boot, trial number 1 ,retrying in 5 seconds Dec 16 20:51:01 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 16 20:51:01 tandberg volumio[1231]: info: CoreStateMachine::pushState Dec 16 20:51:01 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 20:51:01 tandberg volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 20:51:01 tandberg volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 20:51:01 tandberg volumio[1231]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 20:51:01 tandberg volumio[1231]: info: Reloading queue from file Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 20:51:02 tandberg volumio[1231]: info: Setting Device type: Raspberry PI Dec 16 20:51:02 tandberg volumio[1231]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 16 20:51:02 tandberg volumio[1231]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Dec 16 20:51:02 tandberg volumio[1231]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 16 20:51:02 tandberg volumio[1231]: info: CoreStateMachine::setRepeat null single undefined Dec 16 20:51:02 tandberg volumio[1231]: info: CoreStateMachine::pushState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 20:51:02 tandberg volumio[1231]: info: CoreStateMachine::setRandom null Dec 16 20:51:02 tandberg volumio[1231]: info: CoreStateMachine::pushState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreStateMachine::pushState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 16 20:51:02 tandberg sudo[1506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 20:51:02 tandberg sudo[1506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:02 tandberg volumio[1231]: info: Discovery: adding bd54a129-cfdf-4a6d-9d1c-5192e78cb214 Dec 16 20:51:02 tandberg volumio[1231]: info: Discovery: Found device Tandberg Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: info: Discovery: this is already registered, bd54a129-cfdf-4a6d-9d1c-5192e78cb214 Dec 16 20:51:02 tandberg volumio[1231]: info: Discovery: Found device Tandberg Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:02 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183:3000 from 192.168.1.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 20:51:02 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 20:51:02 tandberg volumio[1231]: info: Listing playlists Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 16 20:51:02 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183:3000 from 192.168.1.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 16 20:51:02 tandberg volumio[1231]: info: Completed loading Core Plugins Dec 16 20:51:02 tandberg volumio[1231]: info: Preparing to generate the ALSA configuration file Dec 16 20:51:02 tandberg volumio[1231]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 16 20:51:02 tandberg volumio[1231]: info: Reading ALSA contributions from plugins. Dec 16 20:51:03 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183:3000 from 192.168.1.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 20:51:03 tandberg volumio[1231]: info: Discovery: Getting this device information Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:03 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 20:51:03 tandberg volumio[1231]: info: Asound.conf file unchanged, so no further update is needed Dec 16 20:51:03 tandberg volumio[1231]: info: Output device has changed, restarting MPD Dec 16 20:51:03 tandberg sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 20:51:03 tandberg sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:03 tandberg volumio[1231]: info: Output device has changed, restarting Shairport Sync Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:03 tandberg sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:03 tandberg sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 20:51:03 tandberg sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:03 tandberg volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 20:51:03 tandberg volumio[1231]: info: ___________ START PLUGINS ___________ Dec 16 20:51:03 tandberg volumio[1231]: info: ControllerMpd::onStart: Initializing MPD Dec 16 20:51:03 tandberg volumio[1231]: info: Creating MPD Configuration file Dec 16 20:51:03 tandberg sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 20:51:03 tandberg sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:03 tandberg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 16 20:51:03 tandberg systemd[1]: Starting mpd.service - Music Player Daemon... Dec 16 20:51:03 tandberg sudo[1521]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 20:51:03 tandberg volumio[1231]: info: [1765914663700] CoreMusicLibrary::Adding element Media Servers Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:03 tandberg sudo[1525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 20:51:03 tandberg sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:03 tandberg volumio[1231]: info: UPNP Browser: Client initialized successfully Dec 16 20:51:03 tandberg sudo[1523]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 20:51:03 tandberg sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 20:51:03 tandberg sudo[1527]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 16 20:51:03 tandberg sudo[1523]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:03 tandberg volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 20:51:03 tandberg systemd[1]: mpd.service: Deactivated successfully. Dec 16 20:51:03 tandberg systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 16 20:51:03 tandberg systemd[1]: mpd.socket: Deactivated successfully. Dec 16 20:51:03 tandberg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 16 20:51:03 tandberg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:03 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:03 tandberg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 16 20:51:03 tandberg systemd[1]: Starting mpd.service - Music Player Daemon... Dec 16 20:51:04 tandberg sudo[1534]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 20:51:04 tandberg sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 20:51:04 tandberg sudo[1539]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 16 20:51:04 tandberg sudo[1534]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:04 tandberg volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 20:51:04 tandberg volumio[1231]: info: [1765914664159] CoreMusicLibrary::Adding element Last_100 Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 20:51:04 tandberg volumio[1231]: info: [1765914664179] CoreMusicLibrary::Adding element Webradio Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 20:51:04 tandberg volumio[1231]: info: Initializing BBC Radios Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:04 tandberg volumio[1231]: info: Creating Spotify config file Dec 16 20:51:04 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:04 tandberg systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 16 20:51:04 tandberg systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 20:51:04 tandberg systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 16 20:51:04 tandberg sudo[1506]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:05 tandberg volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 20:51:05 tandberg volumio[1231]: info: [1765914665872] CoreMusicLibrary::Adding element Controradio Dec 16 20:51:05 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:05 tandberg volumio[1231]: Cannot find translation for source Controradio Dec 16 20:51:05 tandberg volumio[1231]: info: Loading i18n strings for locale en Dec 16 20:51:06 tandberg volumio[1231]: info: Loading i18n strings for locale en Dec 16 20:51:06 tandberg volumio[1231]: info: Volumio Calling Home Dec 16 20:51:06 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 16 20:51:06 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Dec 16 20:51:06 tandberg volumio[1231]: info: MPD Permissions set Dec 16 20:51:06 tandberg volumio[1231]: info: MPD Permissions set Dec 16 20:51:06 tandberg volumio[1231]: info: Upmpdcli Daemon Started Dec 16 20:51:06 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:06 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:06 tandberg volumio[1231]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 16 20:51:06 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 20:51:06 tandberg volumio[1231]: info: Listing playlists Dec 16 20:51:06 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 20:51:06 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:06 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetQueue Dec 16 20:51:06 tandberg volumio[1231]: info: CoreStateMachine::getQueue Dec 16 20:51:06 tandberg volumio[1231]: info: CorePlayQueue::getQueue Dec 16 20:51:06 tandberg sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.60/Lenovo/Music /mnt/NAS/PC Dec 16 20:51:06 tandberg sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:06 tandberg kernel: CIFS: Attempting to mount //192.168.1.60/Lenovo/Music Dec 16 20:51:06 tandberg volumio[1231]: info: Spotify config file written Dec 16 20:51:06 tandberg sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 16 20:51:06 tandberg sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:07 tandberg systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 20:51:07 tandberg systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 20:51:07 tandberg systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 16 20:51:07 tandberg go-librespot[1596]: go-librespot daemon starting... Dec 16 20:51:07 tandberg sudo[1594]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg go-librespot[1597]: time="2025-12-16T20:51:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 16 20:51:07 tandberg go-librespot[1597]: time="2025-12-16T20:51:07+01:00" level=debug msg="app state loaded" Dec 16 20:51:07 tandberg go-librespot[1597]: time="2025-12-16T20:51:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 20:51:07 tandberg volumio[1231]: info: No need to fix Spotify hosts Dec 16 20:51:08 tandberg volumio[1231]: info: Volumio called home Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=info msg="zeroconf server listening on port 33739" Dec 16 20:51:08 tandberg volumio[1231]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 16 20:51:08 tandberg volumio[1231]: SPOTIFY: BQDtQaA1Wf3sExBeDZxaDjxSosYEBWA8Lg2TL-8ak_Yna24uQ4xmCeg46C3xsHQShJFnXflPr-jnipElt_-mGXVN3dB9oIuyJrq6-Jk73Ej7Qtfzh6EOlzmM1VM_3r6xgj_J6aWjghkmypJnSeK-A5hvVi6K56Yh8B1CQAA1-LwKjPLYJcf8J8zJPj-OB1EzF5ld3YFQClzqp2jPMlq1ESnN8awiP5nszFfv_i4SYI6NrTz_X9G5yM51m0r-facWuhbRq_TQnJqSNMHF-knYWSOVk6umkihmUZ8 Dec 16 20:51:08 tandberg volumio[1231]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 16 20:51:08 tandberg volumio[1231]: info: New Spotify access token = BQDtQaA1Wf3sExBeDZxaDjxSosYEBWA8Lg2TL-8ak_Yna24uQ4xmCeg46C3xsHQShJFnXflPr-jnipElt_-mGXVN3dB9oIuyJrq6-Jk73Ej7Qtfzh6EOlzmM1VM_3r6xgj_J6aWjghkmypJnSeK-A5hvVi6K56Yh8B1CQAA1-LwKjPLYJcf8J8zJPj-OB1EzF5ld3YFQClzqp2jPMlq1ESnN8awiP5nszFfv_i4SYI6NrTz_X9G5yM51m0r-facWuhbRq_TQnJqSNMHF-knYWSOVk6umkihmUZ8 Dec 16 20:51:08 tandberg volumio[1231]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="obtained new client token: AADGSh22jDudCm0Ai7s6qR/SJ5UpZr4W6yrPfh68ZbRUjjcdYpGsV3/x+9TdX+3Uc9N3/0As5c9p8UOJdCI/976RrZzU9faicixlFXyzV4+hWdgCimIQoztVhEAozEgBj/xJJiKWgBUHoMTz8WPELlBueXIg9noHfeq01hkPN5kLJPTlOX/gVszMzIHfiFZffxp1FnTZx8G4QSHFekDDfFeMm19cH3aCA9ZketFEb4wWliW1rm76yq76XpY=" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="completed keyexchange" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=debug msg="completed challenge" Dec 16 20:51:08 tandberg go-librespot[1597]: time="2025-12-16T20:51:08+01:00" level=info msg="authenticated AP" username="er*****pi" Dec 16 20:51:09 tandberg volumio[1231]: info: Starting Shairport Sync Dec 16 20:51:09 tandberg volumio[1231]: info: Starting Shairport Sync Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=info msg="authenticated Login5" username="er*****pi" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="initializing zeroconf session" username="er*****pi" Dec 16 20:51:09 tandberg volumio[1231]: info: Starting Shairport Sync Dec 16 20:51:09 tandberg mpd[1540]: 2025-12-16T20:51:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 20:51:09 tandberg sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 20:51:09 tandberg sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:09 tandberg systemd[1]: Started mpd.service - Music Player Daemon. Dec 16 20:51:09 tandberg sudo[1511]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg sudo[1525]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg sudo[1619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 20:51:09 tandberg sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:09 tandberg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 20:51:09 tandberg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 20:51:09 tandberg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 20:51:09 tandberg systemd[1]: shairport-sync.service: Consumed 1.219s CPU time. Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="dealer connection opened" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=trace msg="starting accesspoint recv loop" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=trace msg="starting dealer recv loop" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=trace msg="received accesspoint ping" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="received connection id: NTE5MWUxOWMtNmRh...Qjc4RjFBRTgxQg==" Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=trace msg="received accesspoint pong ack" Dec 16 20:51:09 tandberg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 20:51:09 tandberg sudo[1621]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg sudo[1626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 20:51:09 tandberg sudo[1626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:09 tandberg volumio[1231]: info: minidlna: Found miniDLNA version 1.3.0 Dec 16 20:51:09 tandberg volumio[1231]: info: minidlna: Starting minidlna.service Dec 16 20:51:09 tandberg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 16 20:51:09 tandberg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 16 20:51:09 tandberg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 20:51:09 tandberg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 16 20:51:09 tandberg sudo[1626]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg sudo[1619]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg go-librespot[1597]: time="2025-12-16T20:51:09+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 16 20:51:09 tandberg sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 16 20:51:09 tandberg sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:09 tandberg volumio[1231]: info: Shairport-Sync Started Dec 16 20:51:09 tandberg volumio[1231]: Error adding Membership: Error: addMembership EINVAL Dec 16 20:51:09 tandberg volumio[1231]: info: Shairport-Sync Started Dec 16 20:51:09 tandberg volumio[1231]: info: Shairport-Sync Started Dec 16 20:51:09 tandberg systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Dec 16 20:51:09 tandberg sudo[1632]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:09 tandberg volumio[1231]: info: minidlna: systemctl start minidlna.service succeeded. Dec 16 20:51:09 tandberg volumio[1231]: info: Completed starting Core Plugins Dec 16 20:51:09 tandberg volumio[1231]: info: ------------------------------------------- Dec 16 20:51:09 tandberg volumio[1231]: info: ----- MyVolumio plugins startup ---- Dec 16 20:51:09 tandberg volumio[1231]: info: ------------------------------------------- Dec 16 20:51:09 tandberg volumio[1231]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 20:51:10 tandberg volumio[1231]: error: MPD error: The expression evaluated to a falsy value: Dec 16 20:51:10 tandberg volumio[1231]: assert.ok(self.idling) Dec 16 20:51:10 tandberg volumio[1231]: error: The expression evaluated to a falsy value: Dec 16 20:51:10 tandberg volumio[1231]: assert.ok(self.idling) Dec 16 20:51:10 tandberg volumio[1231]: info: MPD running with PID1540 Dec 16 20:51:10 tandberg volumio[1231]: ,establishing connection Dec 16 20:51:10 tandberg volumio[1231]: error: updateQueue error: null Dec 16 20:51:10 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:10 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:10 tandberg volumio[1231]: error: updateQueue error: null Dec 16 20:51:10 tandberg volumio[1231]: SPOTIFY: User informations: {"country":"SE","display_name":"erindpepi","email":"pepierind@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/erindpepi"},"followers":{"href":null,"total":19},"href":"https://api.spotify.com/v1/users/erindpepi","id":"erindpepi","images":[],"product":"premium","type":"user","uri":"spotify:user:erindpepi"} Dec 16 20:51:10 tandberg volumio[1231]: info: Spotify Successfully logged in Dec 16 20:51:10 tandberg volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 20:51:10 tandberg volumio[1231]: info: [1765914670717] CoreMusicLibrary::Adding element Spotify Dec 16 20:51:10 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:10 tandberg volumio[1231]: Cannot find translation for source Controradio Dec 16 20:51:10 tandberg volumio[1231]: Cannot find translation for source Spotify Dec 16 20:51:11 tandberg volumio-remote-updater[758]: [2025-12-16 20:51:11] [connect] Successful connection Dec 16 20:51:11 tandberg volumio-remote-updater[758]: [2025-12-16 20:51:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765914671 101 Dec 16 20:51:11 tandberg volumio[1231]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 17 Dec 16 20:51:11 tandberg volumio[1231]: info: go-librespot daemon successfully initialized Dec 16 20:51:11 tandberg volumio[1231]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.131 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Dec 16 20:51:11 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:11 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:12 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 20:51:12 tandberg volumio[1231]: info: Listing playlists Dec 16 20:51:12 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 20:51:12 tandberg volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 20:51:12 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetQueue Dec 16 20:51:12 tandberg volumio[1231]: info: CoreStateMachine::getQueue Dec 16 20:51:12 tandberg volumio[1231]: info: CorePlayQueue::getQueue Dec 16 20:51:12 tandberg sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 20:51:12 tandberg sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:12 tandberg sudo[1665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 20:51:12 tandberg sudo[1665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:12 tandberg systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 20:51:12 tandberg mpd_monitor.sh[1668]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 20:51:12 tandberg sudo[1663]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:12 tandberg systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 16 20:51:12 tandberg systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 16 20:51:12 tandberg systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 16 20:51:12 tandberg volumio[1231]: info: Successfully started MPD Monitor Dec 16 20:51:12 tandberg systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 16 20:51:12 tandberg mpd_monitor.sh[1670]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 20:51:12 tandberg sudo[1665]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:13 tandberg volumio[1231]: info: Successfully started MPD Monitor Dec 16 20:51:13 tandberg kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 16 20:51:13 tandberg kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 16 20:51:13 tandberg sudo[1587]: pam_unix(sudo:session): session closed for user root Dec 16 20:51:13 tandberg volumio[1231]: info: Cannot mount NAS PC at system boot, trial number 2 ,retrying in 5 seconds Dec 16 20:51:14 tandberg volumio[1231]: info: Initializing connection to go-librespot Websocket Dec 16 20:51:14 tandberg go-librespot[1597]: time="2025-12-16T20:51:14+01:00" level=debug msg="new websocket client" Dec 16 20:51:14 tandberg volumio[1231]: info: Connection to go-librespot Websocket established Dec 16 20:51:14 tandberg volumio[1231]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 20:51:16 tandberg upmpdcli[1688]: writing RSA key Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="handling transfer player command from e428342bc5e8fc3097774c4a48c7a69ca6ec0626" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:1I2DpgYeb1IZjIOw6LOj6L" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=trace msg="fetched new page 0 with 1142 items (list: 1142)" uri="spotify:playlist:1I2DpgYeb1IZjIOw6LOj6L" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="loading track (paused: false, position: 74237ms)" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=trace msg="emitting websocket event: will_play" Dec 16 20:51:16 tandberg volumio[1231]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:1I2DpgYeb1IZjIOw6LOj6L","uri":"spotify:track:0fOlcr3fxEmfVGPPfJqHOP","play_origin":"playlist"}} Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1113" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=warning msg="original track has no formats, alternatives have a total of 4" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="selected format OGG_VORBIS_320 (ba9c270c43c87f7403decb045d6e713688ac8371)" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:16 tandberg go-librespot[1597]: time="2025-12-16T20:51:16+01:00" level=debug msg="requested aes key for file ba9c270c43c87f7403decb045d6e713688ac8371, gid: 0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=debug msg="fetched first chunk of 19, total size is 9612864 bytes" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg volumio[1231]: info: Getting Spotify volume Dec 16 20:51:17 tandberg volumio[1231]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=debug msg="fetched chunk 5/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=trace msg="seek to 74237ms (diff: 87ms, samples: 3273851, bytes: 2905854)" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:17 tandberg go-librespot[1597]: time="2025-12-16T20:51:17+01:00" level=debug msg="created new output device" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=info msg="loaded track \"A La Luna Yo Me Voy\" (paused: false, position: 74237ms, duration: 238320ms, prefetched: false)" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="fetched chunk 6/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=trace msg="scheduling prefetch in 134s" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=trace msg="emitting websocket event: metadata" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=trace msg="emitting websocket event: active" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="sending successful reply for dealer request" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 16 20:51:18 tandberg volumio[1231]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0fOlcr3fxEmfVGPPfJqHOP","name":"A La Luna Yo Me Voy","artist_names":["AfroCubism"],"album_name":"Afrocubism","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cd19ca83980f02b9f676691b","position":74237,"duration":238320,"release_date":"year:2010 month:10 day:11","track_number":10,"disc_number":1}} Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 20:51:18 tandberg go-librespot[1597]: time="2025-12-16T20:51:18+01:00" level=trace msg="emitting websocket event: playing" Dec 16 20:51:18 tandberg sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.60/Lenovo/Music /mnt/NAS/PC Dec 16 20:51:18 tandberg sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 20:51:18 tandberg kernel: CIFS: Attempting to mount //192.168.1.60/Lenovo/Music Dec 16 20:51:18 tandberg volumio[1231]: SPOTIFY: received: {"type":"active","data":null} Dec 16 20:51:18 tandberg volumio[1231]: info: Aligning Spotify Volume to Volumio Volume Dec 16 20:51:18 tandberg volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 20:51:18 tandberg volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 20:51:18 tandberg volumio[1231]: info: Setting Spotify Volume from Volumio: 92 Dec 16 20:51:18 tandberg volumio[1231]: info: Spotify volume: 100 Dec 16 20:51:18 tandberg volumio[1231]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:1I2DpgYeb1IZjIOw6LOj6L","uri":"spotify:track:0fOlcr3fxEmfVGPPfJqHOP","resume":false,"play_origin":"playlist"}} Dec 16 20:51:18 tandberg volumio[1231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 20:51:18 tandberg volumio[1231]: TypeError: Cannot read properties of undefined (reading 'service') Dec 16 20:51:18 tandberg volumio[1231]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 16 20:51:18 tandberg volumio[1231]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Dec 16 20:51:18 tandberg volumio[1231]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 16 20:51:18 tandberg volumio[1231]: at WebSocket.emit (node:events:514:28) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver.emit (node:events:514:28) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 16 20:51:18 tandberg volumio[1231]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 16 20:51:18 tandberg volumio[1231]: at writeOrBuffer (node:internal/streams/writable:399:12) Dec 16 20:51:18 tandberg volumio[1231]: at _write (node:internal/streams/writable:340:10) Dec 16 20:51:18 tandberg volumio[1231]: at Writable.write (node:internal/streams/writable:344:10) Dec 16 20:51:18 tandberg volumio[1231]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 16 20:51:18 tandberg volumio[1231]: at Socket.emit (node:events:514:28) Dec 16 20:51:18 tandberg volumio[1231]: at addChunk (node:internal/streams/readable:343:12) Dec 16 20:51:18 tandberg volumio[1231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=debug msg="handling skip_prev player command from e428342bc5e8fc3097774c4a48c7a69ca6ec0626" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=debug msg="seek track to 0ms" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0fOlcr3fxEmfVGPPfJqHOP" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=trace msg="scheduling prefetch in 208s" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=trace msg="emitting websocket event: seek" Dec 16 20:51:19 tandberg go-librespot[1597]: time="2025-12-16T20:51:19+01:00" level=debug msg="sending successful reply for dealer request" Dec 16 20:51:20 tandberg go-librespot[1597]: time="2025-12-16T20:51:20+01:00" level=debug msg="update volume requested to 65535/65535" Dec 16 20:51:20 tandberg go-librespot[1597]: time="2025-12-16T20:51:20+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 16 20:51:20 tandberg go-librespot[1597]: time="2025-12-16T20:51:20+01:00" level=trace msg="emitting websocket event: volume" Dec 16 20:51:22 tandberg go-librespot[1597]: time="2025-12-16T20:51:22+01:00" level=debug msg="update volume requested to 65535/65535" Dec 16 20:51:22 tandberg sudo[1712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 20:50' Dec 16 20:51:22 tandberg sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"