-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-07-09 16:39:58 CEST. -- Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: VolumeController::SetAlsaVolume69 Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: CoreStateMachine::pushState Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: CorePlayQueue::getTrack 0 Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: CoreCommandRouter::volumioPushState Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: SPOTIFY: SPOTIFY VOLUME 58 Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: SPOTIFY: VOLUMIO VOLUME 69 Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 16:38:05 volumio-rpi4-2gb volumio[821]: info: Setting Spotify Volume from Volumio: 69 Jul 09 16:38:06 volumio-rpi4-2gb volumio[821]: SPOTIFY: SETTING SPOTIFY VOLUME 69 Jul 09 16:38:06 volumio-rpi4-2gb volumio[821]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:38:06 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:06+02:00" level=debug msg="update volume to 45219/65535" Jul 09 16:38:07 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:07+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 16:38:07 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:07+02:00" level=trace msg="emitting websocket event: volume" Jul 09 16:38:07 volumio-rpi4-2gb volumio[821]: SPOTIFY: received: {"type":"volume","data":{"value":69,"max":100}} Jul 09 16:38:07 volumio-rpi4-2gb volumio[821]: SPOTIFY: RECEIVED SPOTIFY VOLUME 69 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: VolumeController::SetAlsaVolume62 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: CoreStateMachine::pushState Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: CorePlayQueue::getTrack 0 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: CoreCommandRouter::volumioPushState Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: SPOTIFY: SPOTIFY VOLUME 69 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: SPOTIFY: VOLUMIO VOLUME 62 Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 16:38:10 volumio-rpi4-2gb volumio[821]: info: Setting Spotify Volume from Volumio: 62 Jul 09 16:38:11 volumio-rpi4-2gb volumio[821]: SPOTIFY: SETTING SPOTIFY VOLUME 62 Jul 09 16:38:11 volumio-rpi4-2gb volumio[821]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:38:11 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:11+02:00" level=debug msg="update volume to 40631/65535" Jul 09 16:38:13 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:13+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 16:38:13 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:38:13+02:00" level=trace msg="emitting websocket event: volume" Jul 09 16:38:13 volumio-rpi4-2gb volumio[821]: SPOTIFY: received: {"type":"volume","data":{"value":62,"max":100}} Jul 09 16:38:13 volumio-rpi4-2gb volumio[821]: SPOTIFY: RECEIVED SPOTIFY VOLUME 62 Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: errno: -3007, Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: code: 'ENOTFOUND', Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: syscall: 'getaddrinfo', Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: hostname: 'lastfm.freetls.fastly.net' Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: } Jul 09 16:39:06 volumio-rpi4-2gb volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:39:07 volumio-rpi4-2gb sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 16:38 Jul 09 16:39:07 volumio-rpi4-2gb sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:07 volumio-rpi4-2gb sudo[2309]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:07 volumio-rpi4-2gb volumio-remote-updater[513]: [2024-07-09 16:39:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 09 16:39:07 volumio-rpi4-2gb volumio-remote-updater[513]: [2024-07-09 16:39:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 09 16:39:07 volumio-rpi4-2gb go-librespot[1071]: time="2024-07-09T16:39:07+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 09 16:39:07 volumio-rpi4-2gb systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 09 16:39:07 volumio-rpi4-2gb systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 09 16:39:07 volumio-rpi4-2gb systemd[1]: Started dynamicswap service. Jul 09 16:39:07 volumio-rpi4-2gb systemd[1]: dynamicswap.service: Succeeded. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: Stopped Volumio Backend Module. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: Started Volumio Backend Module. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: Started dynamicswap service. Jul 09 16:39:08 volumio-rpi4-2gb systemd[1]: dynamicswap.service: Succeeded. Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ----- Volumio3 ---- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ----- System startup ---- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: MYVOLUMIO Environment detected Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Plugin folders cleanup Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning into folder /volumio/app/plugins/ Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category audio_interface Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category miscellanea Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category music_service Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category plugins.json Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category system_controller Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category user_interface Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning into folder /data/plugins/ Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category music_service Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category system_controller Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Scanning category user_interface Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Plugin folders cleanup completed Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ----- Core plugins startup ---- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Loading plugins from folder /volumio/app/plugins/ Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Adding plugin upnp to MyMusic Plugins Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Loading plugins from folder /data/plugins/ Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "system"... Jul 09 16:39:09 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "appearance"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "network"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Refreshing Cached IP Addresses Jul 09 16:39:10 volumio-rpi4-2gb sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 16:39:10 volumio-rpi4-2gb sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:10 volumio-rpi4-2gb sudo[2392]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:10 volumio-rpi4-2gb sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "services"... Jul 09 16:39:10 volumio-rpi4-2gb sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "alsa_controller"... Jul 09 16:39:10 volumio-rpi4-2gb sudo[2394]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:10 volumio-rpi4-2gb sudo[2397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 09 16:39:10 volumio-rpi4-2gb sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "wizard"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "networkfs"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Cannot mount NAS Rasptouch-USB at system boot, trial number 1 ,retrying in 5 seconds Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Starting Udev Watcher for removable devices Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Ignoring mount for partition: boot Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Ignoring mount for partition: volumio Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Ignoring mount for partition: volumio_data Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "volumio_command_line_client"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "upnp"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: [1720535950561] Starting Upmpd Daemon Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "my_music"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "mpd"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "upnp_browser"... Jul 09 16:39:10 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "alarm-clock"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "airplay_emulation"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Starting Shairport Sync Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "last_100"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "webradio"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "i2s_dacs"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: I2S DAC not set, start Auto-detection Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "volumiodiscovery"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** For more information see Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: *** WARNING *** For more information see Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** For more information see Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 16:39:11 volumio-rpi4-2gb node[2323]: *** WARNING *** For more information see Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Discovery: Started advertising with name: Volumio-RPi4-2GB Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "bandcamp"... Jul 09 16:39:11 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "spop"... Jul 09 16:39:12 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "ytmusic"... Jul 09 16:39:12 volumio-rpi4-2gb volumio-remote-updater[513]: [2024-07-09 16:39:12] [connect] Successful connection Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "outputs"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "albumart"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Plugin example_plugin is not enabled Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "inputs"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "updater_comm"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Plugin mpdemulation is not enabled Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "rest_api"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "websocket"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "radio_paradise"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: Forking 3 albumart workers Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Applying required configuration parameters for plugin radio_paradise Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: [1720535953332] [RadioParadise] API delay: 5 Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "backup_restore"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Applying required configuration parameters for plugin backup_restore Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "Systeminfo"... Jul 09 16:39:13 volumio-rpi4-2gb volumio[2323]: info: Loading plugin "touch_display"... Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: Starting albumart workers Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: Starting albumart workers Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Applying required configuration parameters for plugin touch_display Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: Starting albumart workers Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Loading i18n strings for locale en Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: Updating browse sources language Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:14 volumio-rpi4-2gb sudo[2397]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::initPlayerControls Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: Express server listening on port 3000 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: [Metrics] WebUI: 5s 385.67ms Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::resetVolumioState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::getcurrentVolume Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 2 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 3 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 4 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50:3000 from 192.168.178.68 UA: node-XMLHttpRequest Total Clients: 5 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50:3000 from 192.168.178.68 UA: node-XMLHttpRequest Total Clients: 6 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50:3000 from 192.168.178.39 UA: node-XMLHttpRequest Total Clients: 7 Jul 09 16:39:14 volumio-rpi4-2gb volumio-remote-updater[513]: [2024-07-09 16:39:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720535952 101 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 8 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::pushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioPushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::updateTrackBlock Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrackBlock Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Reloading queue from file Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::pushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioPushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::setRepeat null single undefined Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::pushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioPushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::setRandom null Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreStateMachine::pushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioPushState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Setting Device type: Raspberry PI Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 10 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Completed loading Core Plugins Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Preparing to generate the ALSA configuration file Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Reading ALSA contributions from plugins. Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Asound.conf file unchanged, so no further update is needed Jul 09 16:39:14 volumio-rpi4-2gb volumio[2323]: info: Output device has changed, restarting MPD Jul 09 16:39:15 volumio-rpi4-2gb sudo[2485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 16:39:15 volumio-rpi4-2gb sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Output device has changed, restarting Shairport Sync Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb sudo[2485]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:15 volumio-rpi4-2gb sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 16:39:15 volumio-rpi4-2gb sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb systemd[1]: Stopping Music Player Daemon... Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: ___________ START PLUGINS ___________ Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: ControllerMpd::onStart: Initializing MPD Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Creating MPD Configuration file Jul 09 16:39:15 volumio-rpi4-2gb sudo[2495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 16:39:15 volumio-rpi4-2gb sudo[2495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb sudo[2495]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955100] CoreMusicLibrary::Adding element Media Servers Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb sudo[2498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 16:39:15 volumio-rpi4-2gb sudo[2498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955204] CoreMusicLibrary::Adding element Last_100 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955207] CoreMusicLibrary::Adding element Webradio Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955218] CoreMusicLibrary::Adding element Bandcamp Discover Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source Bandcamp Discover Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Creating Spotify config file Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955247] CoreMusicLibrary::Adding element YouTube Music Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source Bandcamp Discover Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source YouTube Music Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: [1720535955251] CoreMusicLibrary::Adding element Radio Paradise Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source Bandcamp Discover Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source YouTube Music Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: Cannot find translation for source Radio Paradise Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Loading i18n strings for locale en Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Volumio Calling Home Jul 09 16:39:15 volumio-rpi4-2gb sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 09 16:39:15 volumio-rpi4-2gb sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 09 16:39:15 volumio-rpi4-2gb sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 09 16:39:15 volumio-rpi4-2gb sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb sudo[2510]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 09 16:39:15 volumio-rpi4-2gb systemd[1]: Reloading. Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: MPD Permissions set Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: MPD Permissions set Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Cannot mount NAS Rasptouch-USB at system boot, trial number 2 ,retrying in 5 seconds Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Listing playlists Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Volumio called home Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Spotify config file written Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb sudo[2532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 09 16:39:15 volumio-rpi4-2gb sudo[2532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:39:15 volumio-rpi4-2gb 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. Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 09 16:39:15 volumio-rpi4-2gb sudo[2544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 09 16:39:15 volumio-rpi4-2gb sudo[2544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb sudo[2544]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Starting Shairport Sync Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Starting Shairport Sync Jul 09 16:39:15 volumio-rpi4-2gb sudo[2547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: Starting Shairport Sync Jul 09 16:39:15 volumio-rpi4-2gb sudo[2547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:39:15 volumio-rpi4-2gb sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:15 volumio-rpi4-2gb volumio[2323]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 09 16:39:15 volumio-rpi4-2gb sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:39:15 volumio-rpi4-2gb sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Reloading. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2516]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: systemctl daemon-reload succeeded. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 09 16:39:16 volumio-rpi4-2gb sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:16 volumio-rpi4-2gb 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. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2512]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Stopping go-librespot Daemon... Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Succeeded. Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Stopped go-librespot Daemon. Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: shairport-sync.service: Succeeded. Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2556]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2549]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb sudo[2547]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb sudo[2553]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb systemd[1]: Started go-librespot Daemon. Jul 09 16:39:16 volumio-rpi4-2gb sudo[2532]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:16 volumio-rpi4-2gb go-librespot[2572]: Librespot-go daemon starting... Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: Shairport-Sync Started Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: Error adding Membership: Error: addMembership EINVAL Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: Shairport-Sync Started Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: Volumio Kiosk started. Jul 09 16:39:16 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:16+02:00" level=info msg="generated new device id: 3a8a1de493656741cc49277957f88ca12cb2725b" Jul 09 16:39:16 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:16+02:00" level=debug msg="stored credentials found for dguoadb6n8w72g3rg3565wqra" Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: Shairport-Sync Started Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: X display number found: 0 Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 09 16:39:16 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:16+02:00" level=debug msg="obtained new client token: AACw9rKJ6GBNfnYRNoTeqJhqQXV5FzfuIwfTHD4t7sblT8xSMmZDTT7TIOHTBi7niNET7xoI++ZzdYhb2npeHAzfHvVWEkBy06NuuBLqcE8+VXKWGk135npSCZQtMSx58yw5tTepGnBP5by2DpUDGyDviSzj18aBnSseGLbH+6PJDJQ6nvmWHHdqfFDuA8bNKq+kbGxwkqyRYg/JiYcxHp5b4Wf0m51g0JzdkFChKA1JP0/gwouejMBrF+auniNl9g==" Jul 09 16:39:16 volumio-rpi4-2gb volumio[2323]: info: touch_display: X display number found: 0 Jul 09 16:39:17 volumio-rpi4-2gb volumio[2323]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 09 16:39:17 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 11 Jul 09 16:39:17 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 09 16:39:17 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:17+02:00" level=debug msg="completed keyexchange" Jul 09 16:39:17 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:17+02:00" level=debug msg="completed challenge" Jul 09 16:39:17 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:17+02:00" level=debug msg="authenticated as dguoadb6n8w72g3rg3565wqra" Jul 09 16:39:19 volumio-rpi4-2gb volumio[2323]: info: go-librespot daemon successfully initialized Jul 09 16:39:20 volumio-rpi4-2gb sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 16:39:20 volumio-rpi4-2gb sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:20 volumio-rpi4-2gb volumio[2323]: info: Cannot mount NAS Rasptouch-USB at system boot, trial number 3 ,retrying in 5 seconds Jul 09 16:39:20 volumio-rpi4-2gb sudo[2592]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:20 volumio-rpi4-2gb sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 16:39:20 volumio-rpi4-2gb sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:20 volumio-rpi4-2gb sudo[2595]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:20 volumio-rpi4-2gb sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 09 16:39:20 volumio-rpi4-2gb sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:20 volumio-rpi4-2gb sudo[2598]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:20 volumio-rpi4-2gb volumio[2323]: info: Upmpdcli Daemon Started Jul 09 16:39:22 volumio-rpi4-2gb volumio[2323]: info: Initializing connection to go-librespot Websocket Jul 09 16:39:25 volumio-rpi4-2gb volumio[2323]: info: Cannot mount NAS Rasptouch-USB at system boot, trial number 4 ,retrying in 5 seconds Jul 09 16:39:25 volumio-rpi4-2gb wpa_supplicant[739]: RRM: Ignoring radio measurement request: Not RRM network Jul 09 16:39:26 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 12 Jul 09 16:39:28 volumio-rpi4-2gb wpa_supplicant[739]: RRM: Ignoring radio measurement request: Not RRM network Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 13 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: adding 54cd853d-51e8-4f28-9fa0-24fa2eb42ef3 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: Found device Volumio-Rasptouch Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: Connecting to remote: 192.168.178.38 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: adding bb3957b1-4410-47fa-adc2-f55321a20916 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: Found device Volumio-RPi2 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: Connecting to remote: 192.168.178.39 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: adding 3997a1c3-e025-4c60-90f1-f437114d97ea Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: Discovery: Found device Volumio-RPi4-2GB Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:28 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Jul 09 16:39:29 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:29+02:00" level=debug msg="new websocket client" Jul 09 16:39:29 volumio-rpi4-2gb volumio[2323]: info: Connection to go-librespot Websocket established Jul 09 16:39:29 volumio-rpi4-2gb volumio[2323]: info: Discovery: Connecting to remote: 192.168.178.38 Jul 09 16:39:30 volumio-rpi4-2gb volumio[2323]: info: Discovery: Connecting to remote: 192.168.178.39 Jul 09 16:39:30 volumio-rpi4-2gb volumio[2323]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 09 16:39:32 volumio-rpi4-2gb volumio[2323]: info: Getting Spotify volume Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: SPOTIFY: SPOTIFY VOLUME undefined Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: SPOTIFY: VOLUMIO VOLUME 62 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: Aligning Spotify Volume to Volumio Volume Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::volumioGetState Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: CorePlayQueue::getTrack 0 Jul 09 16:39:34 volumio-rpi4-2gb volumio[2323]: info: Setting Spotify Volume from Volumio: 62 Jul 09 16:39:35 volumio-rpi4-2gb volumio[2323]: SPOTIFY: SETTING SPOTIFY VOLUME 62 Jul 09 16:39:35 volumio-rpi4-2gb volumio[2323]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:39:35 volumio-rpi4-2gb volumio[2323]: error: Failed to send command to Spotify local API: /player/volume: Error: Internal Server Error Jul 09 16:39:36 volumio-rpi4-2gb volumio[2323]: info: Discovery: Connected to remote: 192.168.178.38 Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: ----- MyVolumio plugins startup ---- Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: ------------------------------------------- Jul 09 16:39:45 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 09 16:39:47 volumio-rpi4-2gb go-librespot[2572]: time="2024-07-09T16:39:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed requesting login5 endpoint: failed requesting login5: Post \"https://login5.spotify.com/v3/login\": dial tcp: lookup login5.spotify.com: i/o timeout" Jul 09 16:39:47 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 16:39:47 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: (node:2323) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: at connResetException (internal/errors.js:607:14) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: at Socket.emit (events.js:327:22) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: (node:2323) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: (node:2323) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jul 09 16:39:47 volumio-rpi4-2gb volumio[2323]: info: Connection to go-librespot Websocket closed Jul 09 16:39:50 volumio-rpi4-2gb volumio[2323]: info: Initializing connection to go-librespot Websocket Jul 09 16:39:50 volumio-rpi4-2gb volumio[2323]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 16:39:50 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 16:39:50 volumio-rpi4-2gb systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 09 16:39:50 volumio-rpi4-2gb systemd[1]: Stopped go-librespot Daemon. Jul 09 16:39:50 volumio-rpi4-2gb systemd[1]: Started go-librespot Daemon. Jul 09 16:39:50 volumio-rpi4-2gb go-librespot[2604]: Librespot-go daemon starting... Jul 09 16:39:50 volumio-rpi4-2gb go-librespot[2604]: time="2024-07-09T16:39:50+02:00" level=info msg="generated new device id: 75067542accafbfc55c95117239a6e31eec3816a" Jul 09 16:39:50 volumio-rpi4-2gb go-librespot[2604]: time="2024-07-09T16:39:50+02:00" level=debug msg="stored credentials found for dguoadb6n8w72g3rg3565wqra" Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bob%20James/47d9a4f5-5703-4d5b-977a-ea1d27b5e94b.jpg' Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: Initializing connection to go-librespot Websocket Jul 09 16:39:53 volumio-rpi4-2gb go-librespot[2604]: time="2024-07-09T16:39:53+02:00" level=debug msg="new websocket client" Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: Connection to go-librespot Websocket established Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: Adding plugin bluetooth to MyMusic Plugins Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: Adding plugin multiroom to MyMusic Plugins Jul 09 16:39:53 volumio-rpi4-2gb volumio[2323]: info: Adding plugin metavolumio to MyMusic Plugins Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: Adding plugin cd_controller to MyMusic Plugins Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: Starting MyVolumio Remote Streaming Endpoints Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: MyVolumio login type: Token Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 09 16:39:54 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: Starting Streaming Service Transparent Proxy Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: Streaming services startup Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: Starting Streaming Daemon Jul 09 16:39:55 volumio-rpi4-2gb sudo[2619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 16:39:55 volumio-rpi4-2gb sudo[2619]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:39:55 volumio-rpi4-2gb sudo[2619]: pam_unix(sudo:session): session closed for user root Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 16 Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: error: Cannot start Volumio Streaming Daemon Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: STREAMING PROXY: Starting server on port 3245 Jul 09 16:39:55 volumio-rpi4-2gb volumio[2323]: Node JS runtime: 14 Jul 09 16:39:56 volumio-rpi4-2gb volumio[2323]: info: Getting Spotify volume Jul 09 16:39:56 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 16 Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: verbose: New Socket.io Connection to 192.168.178.50 from 192.168.178.95 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Total Clients: 17 Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: Error: read ECONNRESET Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: errno: -104, Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: code: 'ECONNRESET', Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: syscall: 'read' Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: } Jul 09 16:39:57 volumio-rpi4-2gb volumio[2323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:39:58 volumio-rpi4-2gb sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 16:38 Jul 09 16:39:58 volumio-rpi4-2gb sudo[2635]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"