-- Logs begin at Thu 2025-05-01 05:49:08 CEST, end at Mon 2025-05-05 19:12:13 CEST. -- May 05 19:11:43 hifi-stue systemd-timedated[1114]: Changed local time to Mon May 5 19:11:43 2025 May 05 19:11:43 hifi-stue sudo[1112]: pam_unix(sudo:session): session closed for user root May 05 19:11:43 hifi-stue volumio-time-update[641]: volumio-time-update-util: System time updated successfully. May 05 19:11:43 hifi-stue systemd[1]: Starting Daily man-db regeneration... May 05 19:11:43 hifi-stue systemd[1]: Starting Daily apt download activities... May 05 19:11:43 hifi-stue systemd[1]: Started Volumio Time Update Utility. May 05 19:11:43 hifi-stue systemd[1]: Reached target Multi-User System. May 05 19:11:43 hifi-stue systemd[1]: Reached target Graphical Interface. May 05 19:11:43 hifi-stue systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 19:11:43 hifi-stue systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 19:11:43 hifi-stue systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 19:11:43 hifi-stue volumio[1069]: info: MYVOLUMIO Environment detected May 05 19:11:43 hifi-stue systemd[1]: man-db.service: Succeeded. May 05 19:11:43 hifi-stue systemd[1]: Started Daily man-db regeneration. May 05 19:11:43 hifi-stue volumio[1069]: info: Plugin folders cleanup May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning into folder /volumio/app/plugins/ May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category audio_interface May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category miscellanea May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category music_service May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category plugins.json May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category system_controller May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category user_interface May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning into folder /data/plugins/ May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category audio_interface May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category music_service May 05 19:11:43 hifi-stue volumio[1069]: info: Scanning category user_interface May 05 19:11:43 hifi-stue volumio[1069]: info: Plugin folders cleanup completed May 05 19:11:43 hifi-stue volumio[1069]: info: ------------------------------------------- May 05 19:11:43 hifi-stue volumio[1069]: info: ----- Core plugins startup ---- May 05 19:11:43 hifi-stue volumio[1069]: info: ------------------------------------------- May 05 19:11:43 hifi-stue volumio[1069]: info: Loading plugins from folder /volumio/app/plugins/ May 05 19:11:43 hifi-stue volumio[1069]: info: Adding plugin upnp to MyMusic Plugins May 05 19:11:43 hifi-stue volumio[1069]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 19:11:43 hifi-stue volumio[1069]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 19:11:43 hifi-stue volumio[1069]: info: Loading plugins from folder /data/plugins/ May 05 19:11:43 hifi-stue volumio[1069]: info: Loading plugin "system"... May 05 19:11:43 hifi-stue volumio[1069]: info: Loading plugin "appearance"... May 05 19:11:43 hifi-stue systemd[1]: systemd-hostnamed.service: Succeeded. May 05 19:11:44 hifi-stue volumio-remote-updater[651]: [2025-05-05 19:11:44] [connect] Successful connection May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "network"... May 05 19:11:46 hifi-stue volumio[1069]: info: Refreshing Cached IP Addresses May 05 19:11:46 hifi-stue sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 19:11:46 hifi-stue sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:46 hifi-stue sudo[1160]: pam_unix(sudo:session): session closed for user root May 05 19:11:46 hifi-stue sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 19:11:46 hifi-stue sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:46 hifi-stue sudo[1163]: pam_unix(sudo:session): session closed for user root May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "services"... May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "alsa_controller"... May 05 19:11:46 hifi-stue sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 19:11:46 hifi-stue sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:46 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "wizard"... May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "networkfs"... May 05 19:11:46 hifi-stue volumio[1069]: info: Starting Udev Watcher for removable devices May 05 19:11:46 hifi-stue sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bjorn,password=Gordon917,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.46/BigBird\\Music /mnt/NAS/BigBird May 05 19:11:46 hifi-stue sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:46 hifi-stue systemd[1]: apt-daily.service: Succeeded. May 05 19:11:46 hifi-stue systemd[1]: Started Daily apt download activities. May 05 19:11:46 hifi-stue volumio[1069]: info: Ignoring mount for partition: boot May 05 19:11:46 hifi-stue volumio[1069]: info: Ignoring mount for partition: volumio May 05 19:11:46 hifi-stue volumio[1069]: info: Ignoring mount for partition: volumio_data May 05 19:11:46 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "volumio_command_line_client"... May 05 19:11:46 hifi-stue systemd[1]: Starting Daily apt upgrade and clean activities... May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "upnp"... May 05 19:11:46 hifi-stue volumio[1069]: info: [1746465106745] Starting Upmpd Daemon May 05 19:11:46 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "my_music"... May 05 19:11:46 hifi-stue volumio[1069]: info: Loading plugin "mpd"... May 05 19:11:47 hifi-stue kernel: Key type cifs.spnego registered May 05 19:11:47 hifi-stue kernel: Key type cifs.idmap registered May 05 19:11:47 hifi-stue 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. May 05 19:11:47 hifi-stue kernel: CIFS: Attempting to mount //192.168.2.46/BigBird/Music May 05 19:11:47 hifi-stue kernel: cryptd: max_cpu_qlen set to 1000 May 05 19:11:47 hifi-stue volumio[1069]: info: Loading plugin "upnp_browser"... May 05 19:11:47 hifi-stue sudo[1206]: pam_unix(sudo:session): session closed for user root May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "alarm-clock"... May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "airplay_emulation"... May 05 19:11:48 hifi-stue volumio[1069]: info: Starting Shairport Sync May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "last_100"... May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "webradio"... May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "i2s_dacs"... May 05 19:11:48 hifi-stue volumio[1069]: info: Loading plugin "volumiodiscovery"... May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** For more information see May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 19:11:48 hifi-stue volumio[1069]: *** WARNING *** For more information see May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** For more information see May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 19:11:48 hifi-stue node[1069]: *** WARNING *** For more information see May 05 19:11:49 hifi-stue volumio[1069]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 19:11:49 hifi-stue volumio[1069]: info: Discovery: Started advertising with name: HiFi-stue May 05 19:11:49 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 19:11:49 hifi-stue volumio[1069]: info: Loading plugin "spop"... May 05 19:11:49 hifi-stue systemd[1]: apt-daily-upgrade.service: Succeeded. May 05 19:11:49 hifi-stue systemd[1]: Started Daily apt upgrade and clean activities. May 05 19:11:49 hifi-stue systemd[1]: Startup finished in 13.877s (kernel) + 45.058s (userspace) = 58.936s. May 05 19:11:49 hifi-stue sudo[1174]: pam_unix(sudo:session): session closed for user root May 05 19:11:51 hifi-stue volumio[1069]: info: Loading plugin "now_playing"... May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "outputs"... May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "albumart"... May 05 19:11:53 hifi-stue volumio[1069]: info: Plugin example_plugin is not enabled May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "inputs"... May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "updater_comm"... May 05 19:11:53 hifi-stue volumio[1069]: info: Plugin mpdemulation is not enabled May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "rest_api"... May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "websocket"... May 05 19:11:53 hifi-stue volumio[1069]: info: Starting Socket.io Server version 2.3.0 May 05 19:11:53 hifi-stue volumio[1069]: info: Plugin fusiondsp is not enabled May 05 19:11:53 hifi-stue volumio[1069]: info: Loading plugin "touch_display"... May 05 19:11:53 hifi-stue volumio[1069]: Forking 3 albumart workers May 05 19:11:54 hifi-stue volumio[1069]: info: Applying required configuration parameters for plugin touch_display May 05 19:11:54 hifi-stue volumio[1069]: info: Loading i18n strings for locale en May 05 19:11:54 hifi-stue volumio[1069]: Updating browse sources language May 05 19:11:54 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 19:11:55 hifi-stue volumio[1069]: Starting albumart workers May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::initPlayerControls May 05 19:11:55 hifi-stue volumio[1069]: Starting albumart workers May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:55 hifi-stue volumio[1069]: Starting albumart workers May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:11:55 hifi-stue volumio[1069]: Express server listening on port 3000 May 05 19:11:55 hifi-stue volumio[1069]: [Metrics] WebUI: 14s 743.39ms May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::resetVolumioState May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::getcurrentVolume May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 05 19:11:55 hifi-stue volumio[1069]: info: Volumio Network Manager: Network status updated: 2 May 05 19:11:55 hifi-stue volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:55 hifi-stue volumio[1069]: info: Reloading queue from file May 05 19:11:55 hifi-stue volumio[1069]: info: VolumeController:: Volume=40 Mute =false May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::pushState May 05 19:11:55 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioPushState May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::updateTrackBlock May 05 19:11:55 hifi-stue volumio[1069]: info: CorePlayQueue::getTrackBlock May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::setRepeat null single undefined May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::pushState May 05 19:11:55 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioPushState May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::setRandom false May 05 19:11:55 hifi-stue volumio[1069]: info: CoreStateMachine::pushState May 05 19:11:55 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 19:11:55 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioPushState May 05 19:11:55 hifi-stue volumio[1069]: info: Setting Device type: Raspberry PI May 05 19:11:56 hifi-stue volumio[1069]: info: Completed loading Core Plugins May 05 19:11:56 hifi-stue volumio[1069]: info: Preparing to generate the ALSA configuration file May 05 19:11:56 hifi-stue volumio[1069]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 05 19:11:56 hifi-stue volumio[1069]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 May 05 19:11:56 hifi-stue volumio[1069]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 05 19:11:56 hifi-stue volumio[1069]: info: Discovery: adding 3b77399d-a166-444b-b26e-08c73f0ccc2a May 05 19:11:56 hifi-stue volumio[1069]: info: Discovery: Found device HiFi-stue May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioGetState May 05 19:11:56 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:56 hifi-stue volumio[1069]: info: Asound.conf file unchanged, so no further update is needed May 05 19:11:56 hifi-stue volumio[1069]: info: Output device has changed, restarting MPD May 05 19:11:56 hifi-stue sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 19:11:56 hifi-stue volumio[1069]: info: Output device has changed, restarting Shairport Sync May 05 19:11:56 hifi-stue sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:56 hifi-stue sudo[1359]: pam_unix(sudo:session): session closed for user root May 05 19:11:56 hifi-stue sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 19:11:56 hifi-stue sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:56 hifi-stue systemd[1]: Stopping Music Player Daemon... May 05 19:11:56 hifi-stue volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 19:11:56 hifi-stue volumio[1069]: info: ___________ START PLUGINS ___________ May 05 19:11:56 hifi-stue volumio[1069]: info: ControllerMpd::onStart: Initializing MPD May 05 19:11:56 hifi-stue volumio[1069]: info: Creating MPD Configuration file May 05 19:11:56 hifi-stue sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 19:11:56 hifi-stue sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 19:11:56 hifi-stue volumio[1069]: info: [1746465116362] CoreMusicLibrary::Adding element Media Servers May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 19:11:56 hifi-stue sudo[1368]: pam_unix(sudo:session): session closed for user root May 05 19:11:56 hifi-stue sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 19:11:56 hifi-stue sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:56 hifi-stue volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:56 hifi-stue systemd[1]: mpd.service: Succeeded. May 05 19:11:56 hifi-stue systemd[1]: Stopped Music Player Daemon. May 05 19:11:56 hifi-stue systemd[1]: Starting Music Player Daemon... May 05 19:11:56 hifi-stue volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 19:11:56 hifi-stue volumio[1069]: info: [1746465116585] CoreMusicLibrary::Adding element Last_100 May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 19:11:56 hifi-stue volumio[1069]: info: [1746465116589] CoreMusicLibrary::Adding element Webradio May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 19:11:56 hifi-stue volumio[1069]: info: Initializing BBC Radios May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:56 hifi-stue sudo[1390]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 19:11:56 hifi-stue sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:56 hifi-stue volumio[1069]: info: Creating Spotify config file May 05 19:11:56 hifi-stue sudo[1390]: pam_unix(sudo:session): session closed for user root May 05 19:11:56 hifi-stue volumio[1069]: info: [now-playing] ConfigUpdater: config is up to date. May 05 19:11:56 hifi-stue volumio[1069]: info: Loading i18n strings for locale en May 05 19:11:56 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 19:11:56 hifi-stue volumio[1069]: info: Volumio Calling Home May 05 19:11:56 hifi-stue sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 05 19:11:56 hifi-stue sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:57 hifi-stue systemd[1]: Reloading. May 05 19:11:57 hifi-stue volumio[1069]: info: [now-playing] App is listening on port 4004. May 05 19:11:57 hifi-stue volumio[1069]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 05 19:11:57 hifi-stue volumio[1069]: info: VolumeController:: Volume=40 Mute =false May 05 19:11:57 hifi-stue volumio[1069]: info: CoreStateMachine::pushState May 05 19:11:57 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioPushState May 05 19:11:57 hifi-stue sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 19:11:57 hifi-stue sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:57 hifi-stue sudo[1426]: pam_unix(sudo:session): session closed for user root May 05 19:11:57 hifi-stue volumio[1069]: info: MPD Permissions set May 05 19:11:57 hifi-stue volumio[1069]: info: MPD Permissions set May 05 19:11:57 hifi-stue sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 19:11:57 hifi-stue sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:57 hifi-stue sudo[1428]: pam_unix(sudo:session): session closed for user root May 05 19:11:57 hifi-stue volumio[1069]: info: Volumio called home May 05 19:11:57 hifi-stue volumio[1069]: info: Spotify config file written May 05 19:11:57 hifi-stue volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 05 19:11:57 hifi-stue sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 05 19:11:57 hifi-stue sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 19:11:57 hifi-stue volumio[1069]: info: touch_display: Raspberry Pi Foundation touch screen detected. May 05 19:11:57 hifi-stue volumio[1069]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. May 05 19:11:57 hifi-stue volumio[1069]: info: Starting Shairport Sync May 05 19:11:57 hifi-stue volumio[1069]: info: Starting Shairport Sync May 05 19:11:57 hifi-stue sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 19:11:57 hifi-stue volumio[1069]: info: Starting Shairport Sync May 05 19:11:57 hifi-stue sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 19:11:58 hifi-stue sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 19:11:58 hifi-stue sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 05 19:11:58 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioGetState May 05 19:11:58 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:58 hifi-stue sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 19:11:58 hifi-stue sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness May 05 19:11:58 hifi-stue sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue sudo[1456]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue volumio[1069]: info: touch_display: File permissions for backlight brightness control set. May 05 19:11:58 hifi-stue sudo[1410]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue volumio[1069]: info: touch_display: systemctl daemon-reload succeeded. May 05 19:11:58 hifi-stue sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 05 19:11:58 hifi-stue sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:11:58 hifi-stue systemd[1]: Started Volumio Kiosk. May 05 19:11:58 hifi-stue sudo[1463]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue volumio[1069]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 05 19:11:58 hifi-stue volumio[1069]: info: touch_display: Volumio Kiosk started May 05 19:11:58 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioGetState May 05 19:11:58 hifi-stue systemd[1]: Started UPnP Renderer front-end to MPD. May 05 19:11:58 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:58 hifi-stue mpd[1405]: May 05 19:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 19:11:58 hifi-stue systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 19:11:58 hifi-stue sudo[1452]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue systemd[1]: Started go-librespot Daemon. May 05 19:11:58 hifi-stue sudo[1432]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue go-librespot[1469]: Librespot-go daemon starting... May 05 19:11:58 hifi-stue systemd[1]: shairport-sync.service: Succeeded. May 05 19:11:58 hifi-stue systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 19:11:58 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioGetState May 05 19:11:58 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:11:58 hifi-stue volumio[1069]: info: Upmpdcli Daemon Started May 05 19:11:58 hifi-stue systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 19:11:58 hifi-stue sudo[1448]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue systemd[1]: Started Music Player Daemon. May 05 19:11:58 hifi-stue sudo[1446]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue sudo[1443]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue sudo[1361]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue volumio[1069]: info: Shairport-Sync Started May 05 19:11:58 hifi-stue sudo[1370]: pam_unix(sudo:session): session closed for user root May 05 19:11:58 hifi-stue volumio[1069]: Error adding Membership: Error: addMembership EINVAL May 05 19:11:59 hifi-stue volumio[1069]: info: Shairport-Sync Started May 05 19:11:59 hifi-stue volumio[1069]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 05 19:11:59 hifi-stue volumio[1069]: info: Shairport-Sync Started May 05 19:11:59 hifi-stue volumio[1069]: info: Completed starting Core Plugins May 05 19:11:59 hifi-stue volumio[1069]: info: ------------------------------------------- May 05 19:11:59 hifi-stue volumio[1069]: info: ----- MyVolumio plugins startup ---- May 05 19:11:59 hifi-stue volumio[1069]: info: ------------------------------------------- May 05 19:11:59 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 19:11:59 hifi-stue startx[1465]: X.Org X Server 1.20.4 May 05 19:11:59 hifi-stue startx[1465]: X Protocol Version 11, Revision 0 May 05 19:11:59 hifi-stue startx[1465]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian May 05 19:11:59 hifi-stue startx[1465]: Current Operating System: Linux hifi-stue 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l May 05 19:11:59 hifi-stue startx[1465]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:3F:F7:D2 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=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 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 May 05 19:11:59 hifi-stue startx[1465]: Build Date: 04 April 2023 07:50:56AM May 05 19:11:59 hifi-stue startx[1465]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) May 05 19:11:59 hifi-stue startx[1465]: Current version of pixman: 0.36.0 May 05 19:11:59 hifi-stue startx[1465]: Before reporting problems, check http://wiki.x.org May 05 19:11:59 hifi-stue startx[1465]: to make sure that you have the latest version. May 05 19:11:59 hifi-stue startx[1465]: Markers: (--) probed, (**) from config file, (==) default setting, May 05 19:11:59 hifi-stue startx[1465]: (++) from command line, (!!) notice, (II) informational, May 05 19:11:59 hifi-stue startx[1465]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 05 19:11:59 hifi-stue startx[1465]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon May 5 19:11:59 2025 May 05 19:11:59 hifi-stue go-librespot[1469]: time="2025-05-05T19:11:59+02:00" level=info msg="generated new device id: c030fd15f4090aa9ef68082396a1ed57e44b1dc3" May 05 19:11:59 hifi-stue startx[1465]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 05 19:11:59 hifi-stue startx[1465]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 05 19:11:59 hifi-stue volumio[1069]: error: MPD error: The expression evaluated to a falsy value: May 05 19:11:59 hifi-stue volumio[1069]: assert.ok(self.idling) May 05 19:11:59 hifi-stue volumio[1069]: error: The expression evaluated to a falsy value: May 05 19:11:59 hifi-stue volumio[1069]: assert.ok(self.idling) May 05 19:11:59 hifi-stue volumio[1069]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 05 19:11:59 hifi-stue volumio[1069]: info: MPD running with PID1405 May 05 19:11:59 hifi-stue volumio[1069]: ,establishing connection May 05 19:11:59 hifi-stue volumio[1069]: error: updateQueue error: null May 05 19:11:59 hifi-stue volumio[1069]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 05 19:11:59 hifi-stue volumio[1069]: error: updateQueue error: null May 05 19:11:59 hifi-stue volumio[1466]: Generating RSA private key, 4096 bit long modulus (2 primes) May 05 19:11:59 hifi-stue volumio-remote-updater[651]: [2025-05-05 19:11:59] [connect] Successful connection May 05 19:11:59 hifi-stue volumio-remote-updater[651]: [2025-05-05 19:11:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746465119 101 May 05 19:11:59 hifi-stue volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 05 19:11:59 hifi-stue go-librespot[1469]: time="2025-05-05T19:11:59+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 05 19:11:59 hifi-stue go-librespot[1469]: time="2025-05-05T19:11:59+02: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]" May 05 19:11:59 hifi-stue go-librespot[1469]: time="2025-05-05T19:11:59+02: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]" May 05 19:11:59 hifi-stue go-librespot[1469]: time="2025-05-05T19:11:59+02:00" level=debug msg="zeroconf server listening on port 35351" May 05 19:12:01 hifi-stue volumio[1069]: info: touch_display: Setting screensaver timeout to 120 seconds. May 05 19:12:01 hifi-stue volumio[1069]: info: go-librespot daemon successfully initialized May 05 19:12:02 hifi-stue volumio[1466]: ............++++ May 05 19:12:04 hifi-stue volumio[1069]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 19:12:04 hifi-stue volumio[1069]: info: Initializing connection to go-librespot Websocket May 05 19:12:05 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:05+02:00" level=debug msg="new websocket client" May 05 19:12:05 hifi-stue volumio[1069]: info: Connection to go-librespot Websocket established May 05 19:12:07 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:07+02:00" level=debug msg="obtained new client token: AACBtKTlDAv1NayOyovsndEhfmbtt7QMxZ79NBtBPmMqSH8M+KhS/uj4JniMpyaXqA2dIQuK0QKP5tqnsOU2ujriPJiFqKpp41lW6o9yDRoAuGUV0mzvau7DLOB9+O0arCi7FCYMwnkvvHNK1K9kd3wRcntHrBWW8pZhu20Dm1di3phTqPr8kL8rzivweCAmVS7Jv9yZ1nGwA0aUt9medplRV5OgdrYFRM0sGoWHlgqkdLlkOn2PGMDxeprvgA==" May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin bluetooth to MyMusic Plugins May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin multiroom to MyMusic Plugins May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin metavolumio to MyMusic Plugins May 05 19:12:07 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:07+02:00" level=debug msg="completed keyexchange" May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin cd_controller to MyMusic Plugins May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 19:12:07 hifi-stue volumio[1069]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 19:12:07 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="completed challenge" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="authenticated as bjorn1234567890" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="authenticated as bjorn1234567890" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="dealer connection opened" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=info msg="accepted zeroconf user bjorn1234567890 from Pixel 8 BK" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 05 19:12:08 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:08+02:00" level=debug msg="received connection id: ZmZjMWRiMzgtNjJmZi00OGI0LWI3MmYtZGVkNzNlNTE4ZTAwK2RlYWxlcit0Y3A6Ly8wYWNhNTg3MS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArREU3MkQ1MUIzNERGNjJBRkQ4MkQxMzZCQzE4MUZCOTEyRkFGQkQyMjJGQ0ExNzQzRTM0NTIyMUJBRjFEN0EyMw==" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="put connect state because NEW_DEVICE" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="handling transfer player command from b9a4f6ac1c70009194f0dc0c6c2ff619cb843b44" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="loading track spotify:track:4kY2MPNBhKk4uJN7NOz2OY (paused: true, position: 11815ms)" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=trace msg="emitting websocket event: will_play" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1126" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4kY2MPNBhKk4uJN7NOz2OY" May 05 19:12:09 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:09+02:00" level=debug msg="requested aes key for file 44adf8372b326f973c47b4dbcf08611538cb814e, gid: 4kY2MPNBhKk4uJN7NOz2OY" May 05 19:12:09 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 19:12:09 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 19:12:09 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:12:09 hifi-stue volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 19:12:09 hifi-stue volumio[1069]: info: Starting MyVolumio Remote Streaming Endpoints May 05 19:12:09 hifi-stue volumio[1069]: info: MyVolumio login type: Token May 05 19:12:09 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 19:12:09 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="fetched first chunk of 12, total size is 6121868 bytes" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=trace msg="seek to 11815ms (diff: 192ms, samples: 521041, bytes: 371693)" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=info msg="loaded track \"What's New\" (uri: spotify:track:4kY2MPNBhKk4uJN7NOz2OY, paused: true, position: 11815ms, duration: 207479ms)" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="fetched chunk 1/11, size: 524288" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=trace msg="emitting websocket event: metadata" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=trace msg="emitting websocket event: active" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="sending successful reply for delaer request" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=trace msg="emitting websocket event: paused" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="fetched chunk 2/11, size: 524288" May 05 19:12:10 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:10+02:00" level=debug msg="fetched chunk 3/11, size: 524288" May 05 19:12:11 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:11+02:00" level=debug msg="update volume to 64880/65535" May 05 19:12:11 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:11+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 05 19:12:11 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:11+02:00" level=trace msg="emitting websocket event: volume" May 05 19:12:12 hifi-stue volumio[1069]: info: Starting Streaming Service Transparent Proxy May 05 19:12:12 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 19:12:12 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 19:12:12 hifi-stue volumio[1069]: info: Streaming services startup May 05 19:12:12 hifi-stue volumio[1069]: info: Starting Streaming Daemon May 05 19:12:12 hifi-stue sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 19:12:12 hifi-stue sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 19:12:12 hifi-stue volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 05 19:12:12 hifi-stue sudo[1786]: pam_unix(sudo:session): session closed for user root May 05 19:12:12 hifi-stue volumio[1069]: info: Getting Spotify volume May 05 19:12:12 hifi-stue volumio[1069]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kY2MPNBhKk4uJN7NOz2OY","play_origin":"com.spotify.gaia"}} May 05 19:12:12 hifi-stue volumio[1069]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4kY2MPNBhKk4uJN7NOz2OY","name":"What's New","artist_names":["Clifford Brown"],"album_name":"Clifford Brown with Strings","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026673ac1d1f1e110d98d63a9b","position":11815,"duration":207479}} May 05 19:12:12 hifi-stue volumio[1069]: SPOTIFY: received: {"type":"active","data":null} May 05 19:12:12 hifi-stue volumio[1069]: info: Aligning Spotify Volume to Volumio Volume May 05 19:12:12 hifi-stue volumio[1069]: info: CoreCommandRouter::volumioGetState May 05 19:12:12 hifi-stue volumio[1069]: info: CorePlayQueue::getTrack 0 May 05 19:12:12 hifi-stue volumio[1069]: info: Setting Spotify Volume from Volumio: 40 May 05 19:12:12 hifi-stue volumio[1069]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4kY2MPNBhKk4uJN7NOz2OY","play_origin":"com.spotify.gaia"}} May 05 19:12:12 hifi-stue volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 19:12:12 hifi-stue volumio[1069]: TypeError: Cannot read property 'service' of undefined May 05 19:12:12 hifi-stue volumio[1069]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:322:50) May 05 19:12:12 hifi-stue volumio[1069]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:256:18) May 05 19:12:12 hifi-stue volumio[1069]: at WebSocket.message (/data/plugins/music_service/spop/index.js:175:14) May 05 19:12:12 hifi-stue volumio[1069]: at WebSocket.emit (events.js:315:20) May 05 19:12:12 hifi-stue volumio[1069]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1192:20) May 05 19:12:12 hifi-stue volumio[1069]: at Receiver.emit (events.js:315:20) May 05 19:12:12 hifi-stue volumio[1069]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:560:14) May 05 19:12:12 hifi-stue volumio[1069]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:478:17) May 05 19:12:12 hifi-stue volumio[1069]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:22) May 05 19:12:12 hifi-stue volumio[1069]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:180:18 May 05 19:12:12 hifi-stue volumio[1069]: at internal/process/task_queues.js:149:7 May 05 19:12:12 hifi-stue volumio[1069]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) May 05 19:12:12 hifi-stue volumio[1069]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) May 05 19:12:12 hifi-stue volumio[1069]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 05 19:12:12 hifi-stue volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=debug msg="update volume to 64224/65535" May 05 19:12:13 hifi-stue systemd[1]: systemd-timedated.service: Succeeded. May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=trace msg="emitting websocket event: volume" May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=debug msg="update volume to 63569/65535" May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 05 19:12:13 hifi-stue go-librespot[1469]: time="2025-05-05T19:12:13+02:00" level=trace msg="emitting websocket event: volume" May 05 19:12:13 hifi-stue sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 19:11 May 05 19:12:13 hifi-stue sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"