-- Logs begin at Thu 2024-09-05 19:16:30 CEST, end at Mon 2024-09-09 11:42:15 CEST. -- Sep 09 11:41:59 volumio ntpd[947]: receive: Unexpected origin timestamp 0xea846603.f26d7afc does not match aorg 0000000000.00000000 from server@194.0.5.123 xmt 0xea894167.34f7a7f8 Sep 09 11:41:59 volumio ntpd[947]: receive: Unexpected origin timestamp 0xea846603.f26bde5a does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xea894167.35033546 Sep 09 11:41:59 volumio ntpd[947]: receive: Unexpected origin timestamp 0xea846603.f25e26ae does not match aorg 0000000000.00000000 from server@82.223.203.159 xmt 0xea894167.35a3fec0 Sep 09 11:41:59 volumio ntpd[947]: receive: Unexpected origin timestamp 0xea846603.f26857c3 does not match aorg 0000000000.00000000 from server@178.32.88.247 xmt 0xea894167.37cc5b1e Sep 09 11:41:59 volumio ntpd[947]: receive: Unexpected origin timestamp 0xea846603.f26a2f89 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xea894167.38d40784 Sep 09 11:41:59 volumio systemd[1]: Starting Daily apt download activities... Sep 09 11:41:59 volumio systemd[1]: apt-daily.service: Succeeded. Sep 09 11:41:59 volumio systemd[1]: Started Daily apt download activities. Sep 09 11:41:59 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 09 11:42:00 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 09 11:42:00 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "outputs"... Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "albumart"... Sep 09 11:42:00 volumio volumio[893]: info: Plugin example_plugin is not enabled Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "inputs"... Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "updater_comm"... Sep 09 11:42:00 volumio volumio[893]: info: Plugin mpdemulation is not enabled Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "rest_api"... Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "websocket"... Sep 09 11:42:00 volumio volumio[893]: info: Starting Socket.io Server version 2.3.0 Sep 09 11:42:00 volumio volumio[893]: info: Loading plugin "touch_display"... Sep 09 11:42:00 volumio volumio[893]: Forking 3 albumart workers Sep 09 11:42:01 volumio volumio[893]: info: Applying required configuration parameters for plugin touch_display Sep 09 11:42:01 volumio volumio[893]: info: Loading i18n strings for locale es Sep 09 11:42:01 volumio volumio[893]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 09 11:42:01 volumio volumio[893]: Updating browse sources language Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 11:42:01 volumio volumio[893]: Starting albumart workers Sep 09 11:42:01 volumio volumio[893]: Starting albumart workers Sep 09 11:42:01 volumio volumio[893]: Starting albumart workers Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::initPlayerControls Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 11:42:01 volumio volumio[893]: Express server listening on port 3000 Sep 09 11:42:01 volumio volumio[893]: [Metrics] WebUI: 9s 791.41ms Sep 09 11:42:01 volumio volumio[893]: info: CoreStateMachine::resetVolumioState Sep 09 11:42:01 volumio volumio[893]: info: CoreStateMachine::getcurrentVolume Sep 09 11:42:01 volumio volumio[893]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 11:42:01 volumio volumio[893]: info: Cannot read play queue from file Sep 09 11:42:02 volumio volumio-remote-updater[639]: [2024-09-09 11:42:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725556610 101 Sep 09 11:42:02 volumio volumio[893]: 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: 1 Sep 09 11:42:02 volumio volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: VolumeController:: Volume=100 Mute =false Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::pushState Sep 09 11:42:02 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Sep 09 11:42:02 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::setRepeat null single undefined Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::pushState Sep 09 11:42:02 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::setRandom null Sep 09 11:42:02 volumio volumio[893]: info: CoreStateMachine::pushState Sep 09 11:42:02 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Sep 09 11:42:02 volumio volumio[893]: info: Setting Device type: Raspberry PI Sep 09 11:42:02 volumio volumio[893]: info: Completed loading Core Plugins Sep 09 11:42:02 volumio volumio[893]: info: Preparing to generate the ALSA configuration file Sep 09 11:42:02 volumio volumio[893]: info: Discovery: A device disappeared from network Sep 09 11:42:02 volumio volumio[893]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 09 11:42:02 volumio volumio[893]: info: Discovery: A device disappeared from network Sep 09 11:42:02 volumio volumio[893]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 09 11:42:02 volumio volumio[893]: info: Asound.conf file unchanged, so no further update is needed Sep 09 11:42:02 volumio volumio[893]: info: Output device has changed, restarting MPD Sep 09 11:42:02 volumio volumio[893]: info: Output device has changed, restarting Shairport Sync Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:02 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 11:42:02 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:02 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:02 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 11:42:02 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:02 volumio volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 11:42:02 volumio volumio[893]: info: ___________ START PLUGINS ___________ Sep 09 11:42:02 volumio volumio[893]: info: ControllerMpd::onStart: Initializing MPD Sep 09 11:42:02 volumio volumio[893]: info: Creating MPD Configuration file Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 11:42:02 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 11:42:02 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:02 volumio volumio[893]: info: [1725874922751] CoreMusicLibrary::Adding element Media Servers Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 11:42:02 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:02 volumio systemd[1]: Listening on mpd.socket. Sep 09 11:42:02 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 11:42:02 volumio systemd[1]: Starting Music Player Daemon... Sep 09 11:42:02 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 09 11:42:02 volumio systemd[1]: mpd.service: Succeeded. Sep 09 11:42:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 09 11:42:02 volumio systemd[1]: Starting Music Player Daemon... Sep 09 11:42:02 volumio volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:02 volumio volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 11:42:02 volumio volumio[893]: info: [1725874922895] CoreMusicLibrary::Adding element Last_100 Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 11:42:02 volumio volumio[893]: info: [1725874922898] CoreMusicLibrary::Adding element Webradio Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 11:42:02 volumio volumio[893]: info: Initializing BBC Radios Sep 09 11:42:02 volumio sudo[1144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 09 11:42:02 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:02 volumio sudo[1144]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 09 11:42:02 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 11:42:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: Creating Spotify config file Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: Loading i18n strings for locale es Sep 09 11:42:03 volumio volumio[893]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 11:42:03 volumio volumio[893]: info: Volumio Calling Home Sep 09 11:42:03 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 09 11:42:03 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:03 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 09 11:42:03 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:03 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 09 11:42:03 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:03 volumio volumio[893]: info: VolumeController:: Volume=100 Mute =false Sep 09 11:42:03 volumio volumio[893]: info: CoreStateMachine::pushState Sep 09 11:42:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Sep 09 11:42:03 volumio volumio[893]: info: Discovery: adding 08cbddca-0b30-4f3b-9cfa-8600cac84da9 Sep 09 11:42:03 volumio volumio[893]: info: Discovery: Found device Volumio Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:03 volumio volumio[893]: info: Discovery: this is already registered, 08cbddca-0b30-4f3b-9cfa-8600cac84da9 Sep 09 11:42:03 volumio volumio[893]: info: Discovery: Found device Volumio Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:03 volumio volumio[893]: info: touch_display: No backlight interface detected. Sep 09 11:42:03 volumio volumio[893]: info: MPD Permissions set Sep 09 11:42:03 volumio volumio[893]: info: MPD Permissions set Sep 09 11:42:03 volumio volumio[893]: info: Spotify config file written Sep 09 11:42:03 volumio systemd[1]: Reloading. Sep 09 11:42:03 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 09 11:42:03 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:03 volumio volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 11:42:03 volumio volumio[893]: error: error Sep 09 11:42:03 volumio volumio[893]: error: error Sep 09 11:42:03 volumio volumio[893]: info: Discovery: this is already registered, 08cbddca-0b30-4f3b-9cfa-8600cac84da9 Sep 09 11:42:03 volumio volumio[893]: info: Discovery: Found device Volumio Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:03 volumio volumio[893]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 09 11:42:03 volumio volumio[893]: info: Discovery: this is already registered, 08cbddca-0b30-4f3b-9cfa-8600cac84da9 Sep 09 11:42:03 volumio volumio[893]: info: Discovery: Found device Volumio Sep 09 11:42:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:03 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 09 11:42:03 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:03 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:03 volumio volumio[893]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/99-vc4.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 09 11:42:03 volumio volumio[893]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/99-vc4.conf': No such file or directory Sep 09 11:42:03 volumio volumio[893]: info: Volumio called home Sep 09 11:42:03 volumio volumio[893]: info: Starting Shairport Sync Sep 09 11:42:03 volumio volumio[893]: info: Starting Shairport Sync Sep 09 11:42:03 volumio volumio[893]: info: Starting Shairport Sync Sep 09 11:42:03 volumio sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 11:42:04 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:04 volumio 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. Sep 09 11:42:04 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 11:42:04 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:04 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:04 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:04 volumio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 11:42:04 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:04 volumio volumio[893]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 09 11:42:04 volumio volumio[893]: SPOTIFY: BQA-Ulm1L3nR7CqWxY0SZS7q0HLOx2j5Ps6G5QNi7bDv1-QojdZHLPfWu6UJs1U967vOsf98SXJMFMIGJhpJd_pr6I5GTynp-5nVroTdWizVxkTZrqzzfyfPluxc19JOoLgmvbO9mbqoABYUtruO36ZgLrROF6hvpkBFiqxtDsKkFJeTiv49qKApAxWJw68n2EswB_0XX67Gd6uYb-F7nFHbTDd8EtYDcHp0z14RLkL2n9Uu48NuEMS3jpbgOM4lW5lhdGo9S0Gls-E Sep 09 11:42:04 volumio volumio[893]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 09 11:42:04 volumio volumio[893]: info: New Spotify access token = BQA-Ulm1L3nR7CqWxY0SZS7q0HLOx2j5Ps6G5QNi7bDv1-QojdZHLPfWu6UJs1U967vOsf98SXJMFMIGJhpJd_pr6I5GTynp-5nVroTdWizVxkTZrqzzfyfPluxc19JOoLgmvbO9mbqoABYUtruO36ZgLrROF6hvpkBFiqxtDsKkFJeTiv49qKApAxWJw68n2EswB_0XX67Gd6uYb-F7nFHbTDd8EtYDcHp0z14RLkL2n9Uu48NuEMS3jpbgOM4lW5lhdGo9S0Gls-E Sep 09 11:42:04 volumio volumio[893]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 09 11:42:04 volumio volumio[893]: SPOTIFY: User informations: {"display_name":"Alain","external_urls":{"spotify":"https://open.spotify.com/user/yrl57q65lwmw79mgsxeygvjh2"},"href":"https://api.spotify.com/v1/users/yrl57q65lwmw79mgsxeygvjh2","id":"yrl57q65lwmw79mgsxeygvjh2","images":[],"type":"user","uri":"spotify:user:yrl57q65lwmw79mgsxeygvjh2","followers":{"href":null,"total":0},"country":"ES","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"alain.garana@gmail.com"} Sep 09 11:42:04 volumio volumio[893]: info: Spotify Successfully logged in Sep 09 11:42:04 volumio volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 11:42:04 volumio volumio[893]: info: [1725874924322] CoreMusicLibrary::Adding element Spotify Sep 09 11:42:04 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 11:42:04 volumio volumio[893]: Cannot find translation for source Spotify Sep 09 11:42:04 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:04 volumio volumio[893]: info: touch_display: systemctl daemon-reload succeeded. Sep 09 11:42:04 volumio systemd[1]: Reloading. Sep 09 11:42:04 volumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 09 11:42:04 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:05 volumio 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. Sep 09 11:42:05 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio volumio[893]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 09 11:42:05 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio volumio[893]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 09 11:42:05 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio volumio[893]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Sep 09 11:42:05 volumio volumio[893]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Sep 09 11:42:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 09 11:42:05 volumio systemd[1]: Started go-librespot Daemon. Sep 09 11:42:05 volumio go-librespot[1229]: Librespot-go daemon starting... Sep 09 11:42:05 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 09 11:42:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 09 11:42:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 09 11:42:05 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:05 volumio volumio[893]: info: Shairport-Sync Started Sep 09 11:42:05 volumio volumio[893]: Error adding Membership: Error: addMembership EINVAL Sep 09 11:42:05 volumio volumio[893]: info: Shairport-Sync Started Sep 09 11:42:05 volumio volumio[893]: info: Shairport-Sync Started Sep 09 11:42:06 volumio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 11:42:06 volumio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:06 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 11:42:06 volumio sudo[1240]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:06 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:06 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:06 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 09 11:42:06 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 11:42:06 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 09 11:42:06 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:06 volumio volumio[893]: info: Upmpdcli Daemon Started Sep 09 11:42:06 volumio go-librespot[1229]: time="2024-09-09T11:42:06+02:00" level=info msg="generated new device id: adc490b37e7a2d73e92068a8f5b82bf957335230" Sep 09 11:42:06 volumio go-librespot[1229]: time="2024-09-09T11:42:06+02:00" level=debug msg="stored credentials found for yrl57q65lwmw79mgsxeygvjh2" Sep 09 11:42:06 volumio mpd[1155]: Sep 09 11:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 09 11:42:06 volumio systemd[1]: Started Music Player Daemon. Sep 09 11:42:06 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:06 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Sep 09 11:42:06 volumio volumio[893]: info: Completed starting Core Plugins Sep 09 11:42:06 volumio volumio[893]: info: ------------------------------------------- Sep 09 11:42:06 volumio volumio[893]: info: ----- MyVolumio plugins startup ---- Sep 09 11:42:06 volumio volumio[893]: info: ------------------------------------------- Sep 09 11:42:06 volumio volumio[893]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 09 11:42:07 volumio volumio[893]: error: MPD error: The expression evaluated to a falsy value: Sep 09 11:42:07 volumio volumio[893]: assert.ok(self.idling) Sep 09 11:42:07 volumio volumio[893]: error: The expression evaluated to a falsy value: Sep 09 11:42:07 volumio volumio[893]: assert.ok(self.idling) Sep 09 11:42:07 volumio volumio[893]: info: MPD running with PID1155 Sep 09 11:42:07 volumio volumio[893]: ,establishing connection Sep 09 11:42:07 volumio volumio[1248]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 09 11:42:07 volumio volumio[893]: error: updateQueue error: null Sep 09 11:42:07 volumio volumio[893]: error: updateQueue error: null Sep 09 11:42:07 volumio go-librespot[1229]: time="2024-09-09T11:42:07+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 09 11:42:07 volumio go-librespot[1229]: time="2024-09-09T11:42:07+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 09 11:42:07 volumio go-librespot[1229]: time="2024-09-09T11:42:07+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 09 11:42:07 volumio go-librespot[1229]: time="2024-09-09T11:42:07+02:00" level=debug msg="zeroconf server listening on port 37909" Sep 09 11:42:07 volumio go-librespot[1229]: time="2024-09-09T11:42:07+02:00" level=debug msg="obtained new client token: AADj7ws+QUPtOJD8/3/AdemSSLR1BVvQwMaf3j2Deia7nGy1yFEPIA7QPIi4qcLfDbWCu0LSeJzWatqWZ88hZsBb/WiUqnLVDlG8i1Mw0nduKLUlKUJlSv2BujDPF77yvyFHPDanjdBqqjQlwXA3Qpoa3WlmdaAocOy/4Za2fOiwJ00bcg9V6B/ANymKpNKpLw0TNEO27knLzEHPt3NAjpZthWgAOKfxn2ZE25zgPQRryAPBdRIzfWrn" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="completed keyexchange" Sep 09 11:42:08 volumio volumio[1248]: .....++++ Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="completed challenge" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="authenticated as yrl57q65lwmw79mgsxeygvjh2" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="authenticated as yrl57q65lwmw79mgsxeygvjh2" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="dealer connection opened" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="initializing zeroconf session, username: yrl57q65lwmw79mgsxeygvjh2" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="autoplay enabled: false" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="received connection id: YzM2ZTZmZjEtYjcwOC00MjgyLWIxNmItNjg2ZTllNmY4Njk4K2RlYWxlcit0Y3A6Ly8wYWNhNDA4ZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRkRDMUUyRkZCMkVBRDhFQjNCMTlBMjQwMDlFOTRCRTM2NDVENkYzMTRGNkE1MkZCMzE3OUQ3N0Y1NzU5Rjk0OA==" Sep 09 11:42:08 volumio volumio[893]: info: go-librespot daemon successfully initialized Sep 09 11:42:08 volumio go-librespot[1229]: time="2024-09-09T11:42:08+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 09 11:42:10 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 09 11:42:11 volumio volumio[893]: info: Initializing connection to go-librespot Websocket Sep 09 11:42:11 volumio go-librespot[1229]: time="2024-09-09T11:42:11+02:00" level=debug msg="new websocket client" Sep 09 11:42:11 volumio volumio[893]: info: Connection to go-librespot Websocket established Sep 09 11:42:12 volumio volumio[893]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="handling transfer player command from 684ae258fb6fcc5e6c862eac50fbd5861ce95ca2" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="resolved context of track" uri="spotify:album:5ARI6MI0J9CnAqT9vfhd4n" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=trace msg="fetched new page 0 with 12 items (list: 12)" uri="spotify:album:5ARI6MI0J9CnAqT9vfhd4n" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="loading track (paused: false, position: 112962ms)" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=trace msg="emitting websocket event: will_play" Sep 09 11:42:13 volumio volumio[893]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:428cFrawSqeLoyooQjAW9d","play_origin":"album"}} Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="selected format OGG_VORBIS_320 (097fac0f9792e4dd87c342cc7245ed9dfe34a74d)" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="requested aes key for file 097fac0f9792e4dd87c342cc7245ed9dfe34a74d, gid: 428cFrawSqeLoyooQjAW9d" Sep 09 11:42:13 volumio go-librespot[1229]: time="2024-09-09T11:42:13+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1380" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched first chunk of 24, total size is 12257572 bytes" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1144" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 10/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 11/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 12/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="fetched chunk 9/23, size: 524288" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=trace msg="seek to 112962ms (diff: 79ms, samples: 4981624, bytes: 5113544)" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02: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" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="created new output device" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=info msg="loaded track \"La casa por el tejado\" (paused: false, position: 112962ms, duration: 267760ms, prefetched: false)" uri="spotify:track:428cFrawSqeLoyooQjAW9d" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=trace msg="scheduling prefetch in 125s" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=trace msg="emitting websocket event: metadata" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=trace msg="emitting websocket event: active" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="sending successful reply for dealer request" Sep 09 11:42:14 volumio volumio[893]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:428cFrawSqeLoyooQjAW9d","name":"La casa por el tejado","artist_names":["Fito y Fitipaldis"],"album_name":"Lo mas lejos a tu lado","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a618985542a38277dc088c3a","position":112962,"duration":267760,"release_date":"year:2003 month:9 day:1","track_number":1,"disc_number":1}} Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 09 11:42:14 volumio volumio[893]: SPOTIFY: received: {"type":"active","data":null} Sep 09 11:42:14 volumio volumio[893]: info: Aligning Spotify Volume to Volumio Volume Sep 09 11:42:14 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Sep 09 11:42:14 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Sep 09 11:42:14 volumio volumio[893]: info: Setting Spotify Volume from Volumio: 100 Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 11:42:14 volumio go-librespot[1229]: time="2024-09-09T11:42:14+02:00" level=trace msg="emitting websocket event: playing" Sep 09 11:42:14 volumio volumio[893]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:428cFrawSqeLoyooQjAW9d","play_origin":"album"}} Sep 09 11:42:14 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 11:42:14 volumio volumio[893]: TypeError: Cannot read property 'service' of undefined Sep 09 11:42:14 volumio volumio[893]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Sep 09 11:42:14 volumio volumio[893]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Sep 09 11:42:14 volumio volumio[893]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Sep 09 11:42:14 volumio volumio[893]: at WebSocket.emit (events.js:315:20) Sep 09 11:42:14 volumio volumio[893]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Sep 09 11:42:14 volumio volumio[893]: at Receiver.emit (events.js:315:20) Sep 09 11:42:14 volumio volumio[893]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Sep 09 11:42:14 volumio volumio[893]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Sep 09 11:42:14 volumio volumio[893]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Sep 09 11:42:14 volumio volumio[893]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Sep 09 11:42:14 volumio volumio[893]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 09 11:42:14 volumio volumio[893]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Sep 09 11:42:14 volumio volumio[893]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Sep 09 11:42:14 volumio volumio[893]: at Socket.emit (events.js:315:20) Sep 09 11:42:14 volumio volumio[893]: at addChunk (internal/streams/readable.js:309:12) Sep 09 11:42:14 volumio volumio[893]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 09 11:42:14 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 11:42:15 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 11:41 Sep 09 11:42:15 volumio sudo[1279]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"