-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-03-18 20:39:09 CET. -- Mar 18 20:38:51 tov-muziek-server systemd-timedated[919]: Changed local time to Tue Mar 18 20:38:51 2025 Mar 18 20:38:51 tov-muziek-server sudo[917]: pam_unix(sudo:session): session closed for user root Mar 18 20:38:51 tov-muziek-server volumio-time-update[534]: volumio-time-update-util: System time updated successfully. Mar 18 20:38:51 tov-muziek-server systemd[1]: Starting Daily apt download activities... Mar 18 20:38:51 tov-muziek-server systemd[1]: Started Volumio Time Update Utility. Mar 18 20:38:51 tov-muziek-server systemd[1]: Reached target Multi-User System. Mar 18 20:38:51 tov-muziek-server systemd[1]: Reached target Graphical Interface. Mar 18 20:38:51 tov-muziek-server systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 18 20:38:51 tov-muziek-server systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 18 20:38:51 tov-muziek-server systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 18 20:38:51 tov-muziek-server kernel: hwmon hwmon1: Undervoltage detected! Mar 18 20:38:52 tov-muziek-server volumio-remote-updater[509]: [2025-03-18 20:38:52] [connect] Successful connection Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "network"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Refreshing Cached IP Addresses Mar 18 20:38:53 tov-muziek-server systemd[1]: apt-daily.service: Succeeded. Mar 18 20:38:53 tov-muziek-server systemd[1]: Started Daily apt download activities. Mar 18 20:38:53 tov-muziek-server sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "services"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "alsa_controller"... Mar 18 20:38:53 tov-muziek-server sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:53 tov-muziek-server sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 20:38:53 tov-muziek-server sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:53 tov-muziek-server sudo[972]: pam_unix(sudo:session): session closed for user root Mar 18 20:38:53 tov-muziek-server systemd[1]: Starting Daily apt upgrade and clean activities... Mar 18 20:38:53 tov-muziek-server sudo[975]: pam_unix(sudo:session): session closed for user root Mar 18 20:38:53 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "wizard"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "networkfs"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Starting Udev Watcher for removable devices Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Ignoring mount for partition: boot Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Ignoring mount for partition: volumio Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Ignoring mount for partition: volumio_data Mar 18 20:38:53 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "volumio_command_line_client"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "upnp"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: [1742326733789] Starting Upmpd Daemon Mar 18 20:38:53 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "my_music"... Mar 18 20:38:53 tov-muziek-server volumio[807]: info: Loading plugin "mpd"... Mar 18 20:38:54 tov-muziek-server volumio[807]: info: Loading plugin "upnp_browser"... Mar 18 20:38:54 tov-muziek-server systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 18 20:38:54 tov-muziek-server systemd[1]: Started Daily apt upgrade and clean activities. Mar 18 20:38:54 tov-muziek-server systemd[1]: Startup finished in 13.816s (kernel) + 23.665s (userspace) = 37.482s. Mar 18 20:38:54 tov-muziek-server volumio[807]: info: Loading plugin "alarm-clock"... Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "airplay_emulation"... Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Starting Shairport Sync Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "last_100"... Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "webradio"... Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "i2s_dacs"... Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "volumiodiscovery"... Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** For more information see Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 20:38:55 tov-muziek-server volumio[807]: *** WARNING *** For more information see Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** For more information see Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 20:38:55 tov-muziek-server node[807]: *** WARNING *** For more information see Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Discovery: Started advertising with name: Tov Muziek Server Mar 18 20:38:55 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 20:38:55 tov-muziek-server volumio[807]: info: Loading plugin "spop"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "outputs"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "albumart"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Plugin example_plugin is not enabled Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "inputs"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "updater_comm"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Plugin mpdemulation is not enabled Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "rest_api"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading plugin "websocket"... Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Starting Socket.io Server version 2.3.0 Mar 18 20:38:57 tov-muziek-server volumio[807]: info: Loading i18n strings for locale nl Mar 18 20:38:57 tov-muziek-server volumio[807]: Updating browse sources language Mar 18 20:38:57 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 20:38:57 tov-muziek-server volumio[807]: Forking 3 albumart workers Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::initPlayerControls Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: Express server listening on port 3000 Mar 18 20:38:58 tov-muziek-server volumio[807]: [Metrics] WebUI: 10s 208.49ms Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::resetVolumioState Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::getcurrentVolume Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Mar 18 20:38:58 tov-muziek-server volumio[807]: info: Volumio Network Manager: Network status updated: 1 Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:38:58 tov-muziek-server volumio[807]: info: Setting Device type: Raspberry PI Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::setRepeat true single undefined Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::pushState Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioPushState Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::setRandom true Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreStateMachine::pushState Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 20:38:58 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioPushState Mar 18 20:38:58 tov-muziek-server volumio[807]: info: Completed loading Core Plugins Mar 18 20:38:58 tov-muziek-server volumio[807]: info: Preparing to generate the ALSA configuration file Mar 18 20:38:59 tov-muziek-server volumio[807]: info: VolumeController:: Volume=41 Mute =false Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreStateMachine::pushState Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioPushState Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreStateMachine::updateTrackBlock Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrackBlock Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Asound.conf file unchanged, so no further update is needed Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Output device has changed, restarting MPD Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Output device has changed, restarting Shairport Sync Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:38:59 tov-muziek-server sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 20:38:59 tov-muziek-server sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:59 tov-muziek-server sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 20:38:59 tov-muziek-server sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:59 tov-muziek-server sudo[1088]: pam_unix(sudo:session): session closed for user root Mar 18 20:38:59 tov-muziek-server systemd[1]: Stopping Music Player Daemon... Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 20:38:59 tov-muziek-server volumio[807]: info: ___________ START PLUGINS ___________ Mar 18 20:38:59 tov-muziek-server volumio[807]: info: ControllerMpd::onStart: Initializing MPD Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Creating MPD Configuration file Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 20:38:59 tov-muziek-server volumio[807]: info: [1742326739492] CoreMusicLibrary::Adding element Media Servers Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 20:38:59 tov-muziek-server sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 20:38:59 tov-muziek-server sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:59 tov-muziek-server sudo[1097]: pam_unix(sudo:session): session closed for user root Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:38:59 tov-muziek-server sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 20:38:59 tov-muziek-server sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:38:59 tov-muziek-server systemd[1]: mpd.service: Succeeded. Mar 18 20:38:59 tov-muziek-server systemd[1]: Stopped Music Player Daemon. Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:38:59 tov-muziek-server systemd[1]: Starting Music Player Daemon... Mar 18 20:38:59 tov-muziek-server systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 18 20:38:59 tov-muziek-server systemd[1]: mpd.service: Succeeded. Mar 18 20:38:59 tov-muziek-server systemd[1]: Stopped Music Player Daemon. Mar 18 20:38:59 tov-muziek-server systemd[1]: Starting Music Player Daemon... Mar 18 20:38:59 tov-muziek-server volumio[807]: Starting albumart workers Mar 18 20:38:59 tov-muziek-server volumio[807]: Starting albumart workers Mar 18 20:38:59 tov-muziek-server volumio[807]: Starting albumart workers Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 20:38:59 tov-muziek-server volumio[807]: info: [1742326739902] CoreMusicLibrary::Adding element Last_100 Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 20:38:59 tov-muziek-server volumio[807]: info: [1742326739911] CoreMusicLibrary::Adding element Webradio Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 20:38:59 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 20:38:59 tov-muziek-server volumio[807]: info: Initializing BBC Radios Mar 18 20:39:00 tov-muziek-server sudo[1107]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 20:39:00 tov-muziek-server sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:00 tov-muziek-server sudo[1107]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:00 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 20:39:00 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:39:00 tov-muziek-server volumio[807]: info: Creating Spotify config file Mar 18 20:39:00 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:00 tov-muziek-server volumio[807]: info: Volumio Calling Home Mar 18 20:39:00 tov-muziek-server volumio[807]: info: Discovery: adding e832d4b3-fe67-429c-8bd7-7d78c74e026b Mar 18 20:39:00 tov-muziek-server volumio[807]: info: Discovery: Found device Tov Muziek Server Mar 18 20:39:00 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioGetState Mar 18 20:39:00 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:39:00 tov-muziek-server volumio[807]: info: MPD Permissions set Mar 18 20:39:00 tov-muziek-server volumio[807]: info: MPD Permissions set Mar 18 20:39:00 tov-muziek-server volumio[807]: info: Volumio called home Mar 18 20:39:01 tov-muziek-server volumio[807]: info: Spotify config file written Mar 18 20:39:01 tov-muziek-server sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 18 20:39:01 tov-muziek-server sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:01 tov-muziek-server systemd[1]: Started go-librespot Daemon. Mar 18 20:39:01 tov-muziek-server go-librespot[1129]: Librespot-go daemon starting... Mar 18 20:39:01 tov-muziek-server sudo[1127]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:01 tov-muziek-server volumio[807]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 20:39:01 tov-muziek-server volumio[807]: info: No need to fix Spotify hosts Mar 18 20:39:01 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:01+01:00" level=info msg="generated new device id: 9bd1bad5f2d827b56c6420e23f7d9801fbddc83e" Mar 18 20:39:01 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:01+01:00" level=debug msg="stored credentials found for io8d6trhmc3wdde4hggqre316" Mar 18 20:39:02 tov-muziek-server volumio[807]: info: VolumeController:: Volume=41 Mute =false Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CoreStateMachine::pushState Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioPushState Mar 18 20:39:02 tov-muziek-server volumio[807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 18 20:39:02 tov-muziek-server volumio[807]: SPOTIFY: BQAhrLWpENg9gr7QBXcV6x6xIF0cMPl2OnveGoaTrs5rnd63y5tLBtz96SBHmukgAni5buGMcBJ1xkp8rJrFDOgTHojsNUZIPn-eoFBqBxuvwFdGjEf3S-roOGuL3gSbHB4SoIU2BeSPHI_bLozkjVlodVda_hwm1LJ1JQPOrqLaPSwgq15p8ZqFTsGX4vKLuX27w7-ArdHVDWNjPx38kAalbJuH_96S4d7LlkQiHWDxJzFBJ90sjBYp61hu0dUl3zCQcdt9-iPOF313Ru87C9EI8eWo7rYyHdsWjTE Mar 18 20:39:02 tov-muziek-server volumio[807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 18 20:39:02 tov-muziek-server volumio[807]: info: New Spotify access token = BQAhrLWpENg9gr7QBXcV6x6xIF0cMPl2OnveGoaTrs5rnd63y5tLBtz96SBHmukgAni5buGMcBJ1xkp8rJrFDOgTHojsNUZIPn-eoFBqBxuvwFdGjEf3S-roOGuL3gSbHB4SoIU2BeSPHI_bLozkjVlodVda_hwm1LJ1JQPOrqLaPSwgq15p8ZqFTsGX4vKLuX27w7-ArdHVDWNjPx38kAalbJuH_96S4d7LlkQiHWDxJzFBJ90sjBYp61hu0dUl3zCQcdt9-iPOF313Ru87C9EI8eWo7rYyHdsWjTE Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Starting Shairport Sync Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Starting Shairport Sync Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Starting Shairport Sync Mar 18 20:39:02 tov-muziek-server sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 20:39:02 tov-muziek-server sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:02 tov-muziek-server sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 20:39:02 tov-muziek-server sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:02 tov-muziek-server sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 20:39:02 tov-muziek-server sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:02 tov-muziek-server systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 20:39:02 tov-muziek-server systemd[1]: shairport-sync.service: Succeeded. Mar 18 20:39:02 tov-muziek-server systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioGetState Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 18 20:39:02 tov-muziek-server systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="zeroconf server listening on port 34549" Mar 18 20:39:02 tov-muziek-server sudo[1169]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:02 tov-muziek-server sudo[1171]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:02 tov-muziek-server systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 20:39:02 tov-muziek-server systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 18 20:39:02 tov-muziek-server systemd[1]: shairport-sync.service: Succeeded. Mar 18 20:39:02 tov-muziek-server systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Shairport-Sync Started Mar 18 20:39:02 tov-muziek-server volumio[807]: Error adding Membership: Error: addMembership EINVAL Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Shairport-Sync Started Mar 18 20:39:02 tov-muziek-server systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="obtained new client token: AACF71vpqbhm4Kjbl7Bl3uIbmQYrmTnLn9yuLckn4n86y5ftvnB4nGQJ5jkJnitS7DZaqQqkjKnoCnkC0fPVeTF3RZgAMVZrrRoNF2OFwV/cUnhGxDbnbE5hspyIvbYvafW89UUNBX/VWyM0o9DQbYkKhJkPf+DpdiSeL6CC0wyGF/Gx2wFutWCTXAy5Aote1QxE4lus/sAidOcxutCzxA587ETibN7uZSYvsJtadcdN8UgoBAdFMUGz1x4=" Mar 18 20:39:02 tov-muziek-server sudo[1175]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Shairport-Sync Started Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 20:39:02 tov-muziek-server volumio[807]: SPOTIFY: User informations: {"country":"NL","display_name":"Krijn","email":"krijn.jan@janse.nu","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/io8d6trhmc3wdde4hggqre316"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/io8d6trhmc3wdde4hggqre316","id":"io8d6trhmc3wdde4hggqre316","images":[],"product":"premium","type":"user","uri":"spotify:user:io8d6trhmc3wdde4hggqre316"} Mar 18 20:39:02 tov-muziek-server volumio[807]: info: Spotify Successfully logged in Mar 18 20:39:02 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:02+01:00" level=debug msg="completed keyexchange" Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 20:39:02 tov-muziek-server volumio[807]: info: [1742326742901] CoreMusicLibrary::Adding element Spotify Mar 18 20:39:02 tov-muziek-server volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 20:39:02 tov-muziek-server volumio[807]: Cannot find translation for source Spotify Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="completed challenge" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="authenticated as io8d6trhmc3wdde4hggqre316" Mar 18 20:39:03 tov-muziek-server mpd[1121]: Mar 18 20:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="authenticated as io8d6trhmc3wdde4hggqre316" Mar 18 20:39:03 tov-muziek-server systemd[1]: Started Music Player Daemon. Mar 18 20:39:03 tov-muziek-server sudo[1090]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:03 tov-muziek-server sudo[1099]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:03 tov-muziek-server volumio[807]: info: Completed starting Core Plugins Mar 18 20:39:03 tov-muziek-server volumio[807]: info: ------------------------------------------- Mar 18 20:39:03 tov-muziek-server volumio[807]: info: ----- MyVolumio plugins startup ---- Mar 18 20:39:03 tov-muziek-server volumio[807]: info: ------------------------------------------- Mar 18 20:39:03 tov-muziek-server volumio[807]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="dealer connection opened" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="initializing zeroconf session, username: io8d6trhmc3wdde4hggqre316" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="autoplay enabled: false" Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="received connection id: NWNjZWFmZjItNjY5Zi00NGNmLTg2ZjYtMzkxNWRkOWM0NWRiK2RlYWxlcit0Y3A6Ly8wYWNhNTgwYS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNjAxQzYwMDM5M0IyMDQxNzE1NjdFMDA4MjY1MDBBQUE0NEQwQzFDOEM4QjM5MTAwMEQ0M0M0QjA3QjQwRTE4OQ==" Mar 18 20:39:03 tov-muziek-server volumio[807]: error: MPD error: The expression evaluated to a falsy value: Mar 18 20:39:03 tov-muziek-server volumio[807]: assert.ok(self.idling) Mar 18 20:39:03 tov-muziek-server volumio[807]: error: The expression evaluated to a falsy value: Mar 18 20:39:03 tov-muziek-server volumio[807]: assert.ok(self.idling) Mar 18 20:39:03 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:03+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 18 20:39:03 tov-muziek-server sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 20:39:03 tov-muziek-server volumio[807]: info: MPD running with PID1121 Mar 18 20:39:03 tov-muziek-server volumio[807]: ,establishing connection Mar 18 20:39:03 tov-muziek-server sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:03 tov-muziek-server volumio[807]: error: updateQueue error: null Mar 18 20:39:03 tov-muziek-server sudo[1202]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:03 tov-muziek-server sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 20:39:03 tov-muziek-server volumio[807]: error: updateQueue error: null Mar 18 20:39:03 tov-muziek-server sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:03 tov-muziek-server sudo[1204]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:03 tov-muziek-server sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 18 20:39:03 tov-muziek-server sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 20:39:04 tov-muziek-server systemd[1]: Started UPnP Renderer front-end to MPD. Mar 18 20:39:04 tov-muziek-server sudo[1208]: pam_unix(sudo:session): session closed for user root Mar 18 20:39:04 tov-muziek-server volumio[807]: info: Upmpdcli Daemon Started Mar 18 20:39:04 tov-muziek-server volumio[1210]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 18 20:39:05 tov-muziek-server volumio[807]: info: go-librespot daemon successfully initialized Mar 18 20:39:05 tov-muziek-server volumio[1210]: ....++++ Mar 18 20:39:05 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:05+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Mar 18 20:39:05 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:05+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.148:52032->34.158.1.133:4070: use of closed network connection" Mar 18 20:39:05 tov-muziek-server systemd[1]: systemd-fsckd.service: Succeeded. Mar 18 20:39:05 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:05+01:00" level=debug msg="obtained new client token: AABWV400R6qAT8oitEHuDn4KJ+JbiheD8aHBDoRNgYLDNA5ISaYzHXZppeKm0X8z07dUUB5yom+VqCBx3EbUJ/BSOdlrFCkBylvXN/bZo4OnKuQ8fCt3CCDQAEus2sLRu9sL2T0iTOvqiTUvO+TzQS94D8uvvf5+bTxxbDz9MpQ8aUaeQHCovQ6Uw95ojDQtvlcGcvCMV6papYQwbCC8rT9x27oFjjuLNV5eBPxzjaDLFvflHlv5ZQqxWmk=" Mar 18 20:39:05 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:05+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 20:39:05 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:05+01:00" level=debug msg="completed keyexchange" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="completed challenge" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="authenticated as jqtov" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="authenticated as jqtov" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="dealer connection opened" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=info msg="accepted zeroconf user jqtov from iPhone" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="autoplay enabled: false" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="received connection id: NTRhOTlmNzYtMzRlMS00YzFjLWE5MjgtZjFmMjk0MDc5M2EyK2RlYWxlcit0Y3A6Ly8wYWNhNTg0OS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNTA2MUVGQTQxQjA3NUVFQjJCNUQ5QUM5MTIyMUZEQTIxMTVGMUMyQTRCQ0FGNkI1RUFFNDQ4MEZCRTdGRjgxRQ==" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="handling transfer player command from aafb0f459f5425ceb4e2055e61ef4d0ac11229d1" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:06 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:06+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page 1 with 50 items (list: 100)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page 2 with 50 items (list: 150)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="fetched new page 3 with 50 items (list: 200)" uri="spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=warning msg="failed seeking to track in context spotify:station:playlist:4jSdxWTgG8by7E83FENa3v" error="failed fetching tracks for seek: failed moving to next index 200 (page 4): failed fetching next page: invalid status code from page at radio-apollo/v5/tracks/spotify:station:playlist:4jSdxWTgG8by7E83FENa3v?salt=872324&autoplay=false&count=50&isVideo=false&prev_tracks=4TwEdnSiTPDR1vg1QZ5K8W,6zP2bIJWqFZtJ8dtxeDzcp,2AMysGXOe0zzZJMtH3Nizb,0UVlOQ3EEiTVOctUOnJ00w,2rmk4LsLFzVll7ceG3V2wq,4z0J2qwLzLe1rXJPdzbe4p,5b5cPscqVEMChvDqscVw26,1iMDLTTnmTivGA4bJ80F85,3Mcxi78U3lCjt8DMKa6YY4,4aVuWgvD0X63hcOCnZtNFA,1yqMgZNrevsWMLWfO2PRp5,73VT36Uooid7s1P5PCiKsv,2Ms33RTRCT6gArrpcrPxmo,3yLHOpxiff47oc9AKZSCT7,6M4nkEPZMj58acftDRTuKL,7zhT90M07NLqJb2MsaqWhM,65OR4ywy8Cgs3FDHK82Idl,7FCfMXYTIiQ9b4hDYs4Iol,3x63A5hq4vldKE8hWIi1tE,46K1MMwKf29weyCTKb2cjM,34dx8DACTJsc3rsJdaEIQw,16hV7KbASg4EWoEymJerRA,3x3K1RP3Zfi2qeMR8kyrNO,0KZ7HZvNL3tMarvwvX9sfE,2IAzLFdULtSCi4lLCLG8xh,1mAMlMxsUBQ4qqQ8FVdjt8,1CsuHmKOfCYxPoO4xjlAWV,1Mys1gf9SkMBAVGGxpkJ7d,3FAclTFfvUuQYnEsptbK8w,4T6FWA703h6H7zk1FoSARw,3K9ClVFSgF9NyONcA2legk,2uaihUX7HbKyXBpFxOUT2a,68Q6c22JNyLUlZYX4dE2tn,6sgnDuroY3cSWDPOIO3yoa,29Wcsq14RbRqItlK0fmEDb,1NCuYqMc8hKMb4cpNTcJbD,4Mg3YzlEp93OhGQol4VqXL,0TPWRwZqAvE3aqVuQx8CQm,2P2rhs7AtvqUzerhAZDbNu,5QO79kh1waicV47BqGRL3g,2z9FUHRkbkV4BObNzbHsFU,2OpEZpU0wW1spa6dBl0nK4,5nPbKG04fhLkIAjcPFaZq7,4Tw9JYF9HOuPRyccNWMgwf,6w2jx3gzQAtoKP22czcANv,7b6sWLSnbZhSIeqNGrJ4py,79e65PpLNYjISrGWo0xU1F,7IL4oYjqU58OrarBeQnn0x,2kuLo7yrcuFdErEn7DahFT,6c6W25YoDGjTq3qSPOga5t&minimal=true?salt=804839&autoplay=false&count=50&isVideo=false&prev_tracks=1CLHLnyMSdTWjJQ8DnbXQv,7FCfMXYTIiQ9b4hDYs4Iol,6C2GZHFFO8uXuMYCHiW5Y4,4z0J2qwLzLe1rXJPdzbe4p,0I1DJdLt9BKOb7GWmWxCjo,2n1lYSf8iDfSIYEBZJsS99,79pfMqEMRo8jzPGX7fyw0K,4dqnFd0dfgh4HgZ1w3AGTE,6wWlaSxOrBizAcpqkuv1FJ,73VT36Uooid7s1P5PCiKsv,1eYY6nY4KiKXAT7QIQKyl5,5OaZGF8EKw7kICKjdlrsvM,4aVuWgvD0X63hcOCnZtNFA,0jCmz1nJWNqcmidLl27z4t,4xqm63jD6sXXbdLrAa09px,2IAzLFdULtSCi4lLCLG8xh,6ggAYREO7PlFwI2vbOG3dp,1fSl5KhbaDKrYK7acnj7I0,46kTKbIAyCGyoFOLiuvqa3,0L9lXMXddmoBbBUeF7A9An,46K1MMwKf29weyCTKb2cjM,1bZr5vH0cfmKu5ODpD0DEO,1vP6m0Nlhet4Ea3CGd9710,21jGcNKet2qwijlDFuPiPb,5RgFlk1fcClZd0Y4SGYhqH,0VjIjW4GlUZAMYd2vXMi3b,1NCuYqMc8hKMb4cpNTcJbD,3vy2b1KHszz7beIyN0ANvn,64SLdE6sV5g4uQIbVIuRCq,2b8XyVHX2UxxiGyUsAMQRf,0MipOE6RTEx3WZL3580AIF,5QO79kh1waicV47BqGRL3g,6fnachl7fIn5dqIjakfJ57,5Xm0aIoIX41pKWKyXZAFdj,626ZUQrsDvWnu4vi3NoOn2,6CKoWCWAqEVWVjpeoJXyNH,4WpmwEdc4RBos8W8icSSxW,7pf5YMJ1KmN3zqw701wmbM,6XUHsYE38CEbYunT983O9G,37Tmv4NnfQeb0ZgUC4fOJj,6tXnRSvuNgOq4QcxpIN54r,2P2rhs7AtvqUzerhAZDbNu,3rmo8F54jFF8OgYsqTxm5d,5dJ2OeLxuV34Ly7DUNR8vY,6mHOcVtsHLMuesJkswc0GZ,37BTh5g05cxBIRYMbw8g2T,70C4NyhjD5OZUMzvWZ3njJ,3zYufmyv6HOuiHn1eMR6Ja,6zFCci00787rGrLkWDwog8,0fsEFwLSJTSHSawhe864Cp?salt=639773&autoplay=false&count=50&isVideo=false&prev_tracks=5XaEiteF9A84F3ESpBg7jV,1TaTBjZFCRpg0sjukC5ZA2,1e8D1BCD2afT56Km7UahpB,2vSLSJH9lHKne3zUKYBVPI,4aVuWgvD0X63hcOCnZtNFA,64SLdE6sV5g4uQIbVIuRCq,4xqm63jD6sXXbdLrAa09px,4Im6GRj17qa7NW76OsJh1s,0h9fnCSnbUgOEgibnQByFv,2jDNpZI9Ydk25K8qMCjpUc,2ExdbieQffMXpY7ygN2YN9,5YqwpwmzMGwUIU9h1kcOK8,3x63A5hq4vldKE8hWIi1tE,4T6FWA703h6H7zk1FoSARw,0FMjqbY3aWo1QDbo3GwXib,4sTmH6e1R3hKZ6V0N9nxAy,7IL4oYjqU58OrarBeQnn0x,4nfpbsiYbB0TVBOeJ7X7Z0,5B5YKjgne3TZzNpMsN9aj1,1NCuYqMc8hKMb4cpNTcJbD,6c6W25YoDGjTq3qSPOga5t,6CKoWCWAqEVWVjpeoJXyNH,6fnachl7fIn5dqIjakfJ57,6sgnDuroY3cSWDPOIO3yoa,7aYwpbYXKVvQtEXqZrqHIh,6xBaclumqPBdkcvXVxpg9u,6mf7BNgXs8JipPr2QILnyN,2OpEZpU0wW1spa6dBl0nK4,37Tmv4NnfQeb0ZgUC4fOJj,5r6lJieb2jRU9lyx4UCPnk,2b8XyVHX2UxxiGyUsAMQRf,6fsiOdVbw2gLtTlhys6oNW,22uzF19LxQW87kOVkR79Fq,3mRViwFBCegDxjCsvOf5gO,0FMz7ipH6rTAolvWIKzU9i,0I1DJdLt9BKOb7GWmWxCjo,6mHOcVtsHLMuesJkswc0GZ,135RMzvVpxXTQFNytGrbWB,0fQuzbQNLcD1ofo7B2NcFI,1OiftjZKzSvIGrogONJalx,2kwt2rsc4VbZc7cHEpblIs,4MdzsLc3uMxAulhPbELWjt,6XUHsYE38CEbYunT983O9G,4n5DRMfFBNJNec1JApfILt,3wtV2ifnHzirkAElgTGh63,0uYmsS0sgCmSXJeCjpycoX,3yLHOpxiff47oc9AKZSCT7,35LDid9nvuYrUyZk5qGD0i,58rFvJfLuVEFel86hkoYez,63T7DJ1AFDD6Bn8VzG6JE8?salt=722141&autoplay=false&count=50&isVideo=false&prev_tracks=1dRqTriUNRHNLrVeADrlEo,7JfSHj3L4TybhDB71K6Ntg,68Q6c22JNyLUlZYX4dE2tn,3KU0ucfc9uTEgo1ngqHPVi,4z0J2qwLzLe1rXJPdzbe4p,2jDNpZI9Ydk25K8qMCjpUc,3y2gSW1nKqeOmbekB88BAN,3wJmcmAh2YAfDSKQR8o1XS,11iIeclbEWUhkmLArE9Hr0,64SLdE6sV5g4uQIbVIuRCq,1a1ZML2NrHHEiW1kzq72fi,5Phy3qS90Q5I8DGcTxmSIL,0nyqOWWYh8m7czu2al30r9,5YqwpwmzMGwUIU9h1kcOK8,5kCnPMaiUNbPxDjL24gGkb,6akuZlJlpLE8uqpU5QJj7o,4I7nXv6iqKh60sWMJdpUbN,1fX0h6SaZBXwmr4haRvirQ,3jOaf031XnMF1WbMBUygul,0PUb5NltgSVRYxz0YWqmwk,66TDFKCZpMRfLcgJpa15xq,7sfpydlJrHpTi533idUuZV,3TLzNuGRtUjv7uljs9axY6,0uYmsS0sgCmSXJeCjpycoX,64RENuDjsz8uz4xCDqok3E,2P2rhs7AtvqUzerhAZDbNu,1WvvmEowf7hiz5EnyAwtTj,24AlaGqIZd2nUspgzc2ph1,3yLHOpxiff47oc9AKZSCT7,7hVNvcQ39SlpIFsZC7gaNr,6zFCci00787rGrLkWDwog8,3mFzIFFFmEXTQs6BDAK2ZZ,1OiftjZKzSvIGrogONJalx,66deoxrDAEK7Qg1p0Vioxe,3K9ClVFSgF9NyONcA2legk,6bwKztAxzgjIqvhcqFRUpB,2WrlysXGhULyfprVfW20UT,5B5YKjgne3TZzNpMsN9aj1,4e40KpzbQahwOO3jh8x6Gt,4035yp8Z7EyAg3yDchzxA8,4Mg3YzlEp93OhGQol4VqXL,7rRtFlqRFcR2GreZX9tixl,0srHt7dvF0U5ReVRVS3fz0,6HXGmN7Gh9mdutkMb9tCdj,21Yma8Duwl4pHqGVWV7YCc,0bmHzq6Nx4HnGba1yVO1Zd,2vSLSJH9lHKne3zUKYBVPI,441AvnE885suBHXPxq2F8o,2eJYg5IY30FCczfLWAvPh4,2GRgfuqbBkJMHcTouipXzR: 414" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="loading track (paused: false, position: 5957476ms)" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:07 tov-muziek-server volumio-remote-updater[509]: [2025-03-18 20:39:07] [connect] Successful connection Mar 18 20:39:07 tov-muziek-server volumio-remote-updater[509]: [2025-03-18 20:39:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742326747 101 Mar 18 20:39:07 tov-muziek-server volumio[807]: 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: 2 Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=trace msg="emitting websocket event: will_play" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="selected format OGG_VORBIS_320 (f7ace95d04ca3b609155493de623f39a166bb7b9)" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="requested aes key for file f7ace95d04ca3b609155493de623f39a166bb7b9, gid: 4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:07 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1334" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched first chunk of 16, total size is 8388473 bytes" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server volumio[807]: info: Initializing connection to go-librespot Websocket Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1118" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="new websocket client" Mar 18 20:39:08 tov-muziek-server volumio[807]: info: Connection to go-librespot Websocket established Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched chunk 15/15, size: 524153" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="seek to 187086ms (diff: 191ms, samples: 8250492, bytes: 8378757)" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="created new output device" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=info msg="loaded track \"Beautiful People\" (paused: false, position: 5957476ms, duration: 187086ms, prefetched: false)" uri="spotify:track:4TwEdnSiTPDR1vg1QZ5K8W" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="cleared closed output device" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="scheduling prefetch in 157s" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="emitting websocket event: metadata" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="emitting websocket event: active" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="sending successful reply for dealer request" Mar 18 20:39:08 tov-muziek-server volumio[807]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4TwEdnSiTPDR1vg1QZ5K8W","name":"Beautiful People","artist_names":["David Guetta","Sia"],"album_name":"Beautiful People","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027091ac4e6518b4c612831312","position":5957476,"duration":187086,"release_date":"year:2025 month:3 day:7","track_number":1,"disc_number":1}} Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 18 20:39:08 tov-muziek-server volumio[807]: SPOTIFY: received: {"type":"active","data":null} Mar 18 20:39:08 tov-muziek-server volumio[807]: info: Aligning Spotify Volume to Volumio Volume Mar 18 20:39:08 tov-muziek-server volumio[807]: info: CoreCommandRouter::volumioGetState Mar 18 20:39:08 tov-muziek-server volumio[807]: info: CorePlayQueue::getTrack 0 Mar 18 20:39:08 tov-muziek-server volumio[807]: info: Setting Spotify Volume from Volumio: 41 Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="emitting websocket event: playing" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="emitting websocket event: not_playing" Mar 18 20:39:08 tov-muziek-server volumio[807]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4TwEdnSiTPDR1vg1QZ5K8W","play_origin":""}} Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:08 tov-muziek-server volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 20:39:08 tov-muziek-server volumio[807]: TypeError: Cannot read property 'service' of undefined Mar 18 20:39:08 tov-muziek-server volumio[807]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Mar 18 20:39:08 tov-muziek-server volumio[807]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Mar 18 20:39:08 tov-muziek-server volumio[807]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Mar 18 20:39:08 tov-muziek-server volumio[807]: at WebSocket.emit (events.js:315:20) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.emit (events.js:315:20) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 18 20:39:08 tov-muziek-server volumio[807]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 18 20:39:08 tov-muziek-server volumio[807]: at Socket.emit (events.js:315:20) Mar 18 20:39:08 tov-muziek-server volumio[807]: at addChunk (internal/streams/readable.js:309:12) Mar 18 20:39:08 tov-muziek-server volumio[807]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 18 20:39:08 tov-muziek-server volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="emitting websocket event: will_play" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="selected format OGG_VORBIS_320 (fbdcd8ba53e1a18ae4f47b52268ca404ef9380fd)" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="requested aes key for file fbdcd8ba53e1a18ae4f47b52268ca404ef9380fd, gid: 6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="fetched first chunk of 16, total size is 8212523 bytes" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=debug msg="created new output device" Mar 18 20:39:08 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:08+01:00" level=info msg="loaded track \"Girl\" (paused: false, position: 0ms, duration: 212920ms, prefetched: false)" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=trace msg="scheduling prefetch in 183s" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=trace msg="emitting websocket event: metadata" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=trace msg="emitting websocket event: stopped" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=trace msg="emitting websocket event: playing" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:09 tov-muziek-server go-librespot[1129]: time="2025-03-18T20:39:09+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:6zP2bIJWqFZtJ8dtxeDzcp" Mar 18 20:39:09 tov-muziek-server sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 20:38 Mar 18 20:39:09 tov-muziek-server sudo[1240]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"