-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Fri 2025-01-17 19:54:50 CST. --
Jan 17 19:53:00 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:06 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:12 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:18 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:19 volumio volumio[24785]: error: error
Jan 17 19:53:25 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:25 volumio sudo[24797]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:31 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:33 volumio volumio[24785]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Jan 17 19:53:33 volumio volumio[24785]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/remotecontrol.zip
Jan 17 19:53:33 volumio volumio[24785]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/remotecontrol.zip
Jan 17 19:53:34 volumio volumio[24785]: info: Check plugin dependencies
Jan 17 19:53:34 volumio volumio[24785]: info: Rename folder
Jan 17 19:53:34 volumio volumio[24785]: info: Folder /tmp/downloaded_plugin.zip removed
Jan 17 19:53:34 volumio volumio[24785]: info: Move to category
Jan 17 19:53:34 volumio volumio[24785]: info: Checking if install.sh is present
Jan 17 19:53:34 volumio volumio[24785]: info: Executing install.sh
Jan 17 19:53:34 volumio sudo[26223]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/remotecontrol/install.sh
Jan 17 19:53:34 volumio sudo[26223]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:34 volumio volumio[24785]: info: Installing remotecontrol Dependencies
Jan 17 19:53:34 volumio sudo[26225]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update
Jan 17 19:53:34 volumio sudo[26225]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:35 volumio volumio[24785]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease
Jan 17 19:53:35 volumio volumio[24785]: info: Hit:2 http://raspbian.raspberrypi.org/raspbian buster InRelease
Jan 17 19:53:35 volumio volumio[24785]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease
Jan 17 19:53:37 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:38 volumio volumio[24785]: info: Reading package lists...
Jan 17 19:53:38 volumio sudo[26225]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:38 volumio sudo[26449]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install
Jan 17 19:53:38 volumio sudo[26449]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:38 volumio volumio[24785]: info: Reading package lists...
Jan 17 19:53:40 volumio sudo[26449]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:40 volumio sudo[26223]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:41 volumio volumio[24785]: info: Install script completed
Jan 17 19:53:41 volumio volumio[24785]: info: Done installing plugin.
Jan 17 19:53:41 volumio volumio[24785]: info: Enabling plugin remotecontrol
Jan 17 19:53:41 volumio volumio[24785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 17 19:53:41 volumio volumio[24785]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.1.2","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.2.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"NFC Controller","name":"nfc_controller","category":"user_interface","version":"1.3.1","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RemoteControl","name":"remotecontrol","category":"user_interface","version":"1.0.0","icon":"fa-gamepad","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}]
Jan 17 19:53:41 volumio volumio[24785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jan 17 19:53:41 volumio volumio[24785]: info: Folder /tmp/downloaded_plugin.zip removed
Jan 17 19:53:41 volumio volumio[24785]: info: Building dependency tree...
Jan 17 19:53:41 volumio volumio[24785]: info: Reading state information...
Jan 17 19:53:41 volumio volumio[24785]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded.
Jan 17 19:53:41 volumio volumio[24785]: Plugin install end detected on script
Jan 17 19:53:41 volumio volumio[24785]: info: Folder /data/temp removed
Jan 17 19:53:41 volumio volumio[24785]: info: Folder /tmp/plugins removed
Jan 17 19:53:41 volumio sudo[26490]:  volumio : TTY=pts/0 ; PWD=/home/volumio/custom/volumio-plugins-sources/remotecontrol ; USER=root ; COMMAND=/bin/systemctl stop volumio.service
Jan 17 19:53:41 volumio sudo[26490]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Jan 17 19:53:41 volumio systemd[1]: Stopping Volumio Backend Module...
Jan 17 19:53:41 volumio volumio-remote-updater[653]: [2025-01-17 19:53:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Jan 17 19:53:41 volumio volumio-remote-updater[653]: [2025-01-17 19:53:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Jan 17 19:53:41 volumio go-librespot[25059]: time="2025-01-17T19:53:41-06:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF"
Jan 17 19:53:41 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM
Jan 17 19:53:41 volumio systemd[1]: volumio.service: Succeeded.
Jan 17 19:53:41 volumio systemd[1]: Stopped Volumio Backend Module.
Jan 17 19:53:41 volumio sudo[26490]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:41 volumio sudo[26493]:  volumio : TTY=pts/0 ; PWD=/home/volumio/custom/volumio-plugins-sources/remotecontrol ; USER=root ; COMMAND=/bin/systemctl start volumio.service
Jan 17 19:53:41 volumio sudo[26493]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Jan 17 19:53:41 volumio systemd[1]: Started dynamicswap service.
Jan 17 19:53:41 volumio systemd[1]: Started Volumio Backend Module.
Jan 17 19:53:41 volumio sudo[26493]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:41 volumio systemd[1]: dynamicswap.service: Succeeded.
Jan 17 19:53:41 volumio sudo[26511]:  volumio : TTY=pts/0 ; PWD=/home/volumio/custom/volumio-plugins-sources/remotecontrol ; USER=root ; COMMAND=/bin/journalctl -f
Jan 17 19:53:41 volumio sudo[26511]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Jan 17 19:53:42 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:42 volumio volumio[26499]: info: -----            Volumio3              ----
Jan 17 19:53:42 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:42 volumio volumio[26499]: info: -----          System startup          ----
Jan 17 19:53:42 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:42 volumio volumio[26499]: info: MYVOLUMIO Environment detected
Jan 17 19:53:42 volumio volumio[26499]: info: Plugin folders cleanup
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning into folder /volumio/app/plugins/
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category audio_interface
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category miscellanea
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category music_service
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category plugins.json
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category system_controller
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category user_interface
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning into folder /data/plugins/
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category music_service
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category system_controller
Jan 17 19:53:42 volumio volumio[26499]: info: Scanning category user_interface
Jan 17 19:53:42 volumio volumio[26499]: info: Plugin folders cleanup completed
Jan 17 19:53:42 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:42 volumio volumio[26499]: info: -----      Core plugins startup        ----
Jan 17 19:53:42 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:42 volumio volumio[26499]: info: Loading plugins from folder /volumio/app/plugins/
Jan 17 19:53:42 volumio volumio[26499]: info: Adding plugin upnp to MyMusic Plugins
Jan 17 19:53:42 volumio volumio[26499]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jan 17 19:53:42 volumio volumio[26499]: info: Adding plugin upnp_browser to MyMusic Plugins
Jan 17 19:53:42 volumio volumio[26499]: info: Loading plugins from folder /data/plugins/
Jan 17 19:53:42 volumio volumio[26499]: info: Loading plugin "system"...
Jan 17 19:53:42 volumio volumio[26499]: info: Loading plugin "appearance"...
Jan 17 19:53:43 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "network"...
Jan 17 19:53:43 volumio volumio[26499]: info: Refreshing Cached IP Addresses
Jan 17 19:53:43 volumio sudo[26530]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 17 19:53:43 volumio sudo[26530]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:43 volumio sudo[26532]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 17 19:53:43 volumio sudo[26532]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:43 volumio sudo[26530]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:43 volumio sudo[26532]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "services"...
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "alsa_controller"...
Jan 17 19:53:43 volumio sudo[26541]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jan 17 19:53:43 volumio sudo[26541]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:43 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "wizard"...
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "networkfs"...
Jan 17 19:53:43 volumio volumio[26499]: info: Starting Udev Watcher for removable devices
Jan 17 19:53:43 volumio volumio[26499]: info: Ignoring mount for partition: boot
Jan 17 19:53:43 volumio volumio[26499]: info: Ignoring mount for partition: volumio
Jan 17 19:53:43 volumio volumio[26499]: info: Ignoring mount for partition: volumio_data
Jan 17 19:53:43 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "volumio_command_line_client"...
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "upnp"...
Jan 17 19:53:43 volumio volumio[26499]: info: [1737165223632] Starting Upmpd Daemon
Jan 17 19:53:43 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "my_music"...
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "mpd"...
Jan 17 19:53:43 volumio volumio[26499]: info: Loading plugin "upnp_browser"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "alarm-clock"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "airplay_emulation"...
Jan 17 19:53:44 volumio volumio[26499]: info: Starting Shairport Sync
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "last_100"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "webradio"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "i2s_dacs"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "volumiodiscovery"...
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** For more information see 
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** For more information see 
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 17 19:53:44 volumio volumio[26499]: *** WARNING *** For more information see 
Jan 17 19:53:44 volumio node[26499]: *** WARNING *** For more information see 
Jan 17 19:53:44 volumio volumio[26499]: info: Applying required configuration parameters for plugin volumiodiscovery
Jan 17 19:53:44 volumio volumio[26499]: info: Discovery: Started advertising with name: Volumio
Jan 17 19:53:44 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "spop"...
Jan 17 19:53:44 volumio volumio[26499]: info: Loading plugin "ytcr"...
Jan 17 19:53:46 volumio volumio-remote-updater[653]: [2025-01-17 19:53:46] [connect] Successful connection
Jan 17 19:53:46 volumio volumio[26499]: info: Loading plugin "ytmusic"...
Jan 17 19:53:47 volumio sudo[26541]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:47 volumio volumio[26499]: info: Loading plugin "nfc_controller"...
Jan 17 19:53:47 volumio volumio[26499]: info: Applying required configuration parameters for plugin nfc_controller
Jan 17 19:53:48 volumio volumio[26499]: info: NFCController initialized
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "now_playing"...
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "outputs"...
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "albumart"...
Jan 17 19:53:48 volumio volumio[26499]: info: Plugin example_plugin is not enabled
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "inputs"...
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "updater_comm"...
Jan 17 19:53:48 volumio volumio[26499]: info: Plugin mpdemulation is not enabled
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "rest_api"...
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "websocket"...
Jan 17 19:53:48 volumio volumio[26499]: info: Starting Socket.io Server version 2.3.0
Jan 17 19:53:48 volumio volumio[26499]: info: Loading plugin "backup_restore"...
Jan 17 19:53:48 volumio volumio[26499]: Forking 3 albumart workers
Jan 17 19:53:49 volumio volumio[26499]: info: Applying required configuration parameters for plugin backup_restore
Jan 17 19:53:49 volumio volumio[26499]: info: Loading plugin "peppyspectrum"...
Jan 17 19:53:49 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:49 volumio volumio[26499]: info: Loading plugin "randomizer"...
Jan 17 19:53:49 volumio volumio[26499]: info: Applying required configuration parameters for plugin randomizer
Jan 17 19:53:49 volumio volumio[26499]: info: Loading plugin "remotecontrol"...
Jan 17 19:53:49 volumio volumio[26499]: info: Applying required configuration parameters for plugin remotecontrol
Jan 17 19:53:49 volumio volumio[26499]: Starting albumart workers
Jan 17 19:53:49 volumio volumio[26499]: Starting albumart workers
Jan 17 19:53:49 volumio volumio[26499]: info: Loading plugin "touch_display"...
Jan 17 19:53:49 volumio volumio[26499]: Starting albumart workers
Jan 17 19:53:50 volumio volumio[26499]: info: Applying required configuration parameters for plugin touch_display
Jan 17 19:53:50 volumio volumio[26499]: info: Loading i18n strings for locale en
Jan 17 19:53:50 volumio volumio[26499]: Updating browse sources language
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::initPlayerControls
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: Express server listening on port 3000
Jan 17 19:53:50 volumio volumio[26499]:  [Metrics]  WebUI:  8s 352.13ms 
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::resetVolumioState
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::getcurrentVolume
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioRetrievevolume
Jan 17 19:53:50 volumio volumio[26499]: info: Volumio Network Manager: Network status updated: 2
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 1
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to 192.168.13.224 from 192.168.13.176 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 3
Jan 17 19:53:50 volumio volumio-remote-updater[653]: [2025-01-17 19:53:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737165226 101
Jan 17 19:53:50 volumio volumio[26499]: 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: 4
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to 192.168.13.224 from 192.168.13.176 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 6
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Jan 17 19:53:50 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: Reloading queue from file
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::setRepeat null single undefined
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::pushState
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioPushState
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::setRandom true
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::pushState
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioPushState
Jan 17 19:53:50 volumio volumio[26499]: info: Setting Device type: Raspberry PI
Jan 17 19:53:50 volumio volumio[26499]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jan 17 19:53:50 volumio volumio[26499]: info: USB Boot Capable - System SBC Revision found in cpuinfo:  c03115
Jan 17 19:53:50 volumio volumio[26499]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jan 17 19:53:50 volumio volumio[26499]: info: Completed loading Core Plugins
Jan 17 19:53:50 volumio volumio[26499]: info: Preparing to generate the ALSA configuration file
Jan 17 19:53:50 volumio volumio[26499]: info: VolumeController:: Volume=56 Mute =false
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::pushState
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioPushState
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::updateTrackBlock
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrackBlock
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioRetrievevolume
Jan 17 19:53:50 volumio volumio[26499]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Jan 17 19:53:50 volumio volumio[26499]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Jan 17 19:53:50 volumio volumio[26499]: info: Reading ALSA contributions from plugins.
Jan 17 19:53:50 volumio volumio[26499]: info: Discovery: adding c82c5045-cac1-4b28-b056-0256012305b4
Jan 17 19:53:50 volumio volumio[26499]: info: Discovery: Found device Volumio
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:50 volumio volumio[26499]: info: VolumeController:: Volume=56 Mute =false
Jan 17 19:53:50 volumio volumio[26499]: info: CoreStateMachine::pushState
Jan 17 19:53:50 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::volumioPushState
Jan 17 19:53:50 volumio volumio[26499]: info: Asound.conf file unchanged, so no further update is needed
Jan 17 19:53:50 volumio volumio[26499]: info: Output device has changed, restarting MPD
Jan 17 19:53:50 volumio sudo[26627]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 17 19:53:50 volumio sudo[26627]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:50 volumio sudo[26627]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:50 volumio volumio[26499]: info: Output device has changed, restarting Shairport Sync
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:50 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:50 volumio sudo[26631]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 17 19:53:50 volumio sudo[26631]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:50 volumio systemd[1]: Stopping Music Player Daemon...
Jan 17 19:53:50 volumio systemd[1]: mpd.service: Succeeded.
Jan 17 19:53:50 volumio systemd[1]: Stopped Music Player Daemon.
Jan 17 19:53:50 volumio volumio[26499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 17 19:53:50 volumio volumio[26499]: info: ___________ START PLUGINS ___________
Jan 17 19:53:50 volumio volumio[26499]: info: ControllerMpd::onStart: Initializing MPD
Jan 17 19:53:50 volumio volumio[26499]: info: Creating MPD Configuration file
Jan 17 19:53:51 volumio sudo[26638]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 17 19:53:51 volumio sudo[26638]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:51 volumio volumio[26499]: info: [1737165231042] CoreMusicLibrary::Adding element Media Servers
Jan 17 19:53:51 volumio sudo[26638]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:51 volumio systemd[1]: Starting Music Player Daemon...
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:51 volumio sudo[26641]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 17 19:53:51 volumio sudo[26641]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:51 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Jan 17 19:53:51 volumio systemd[1]: mpd.service: Succeeded.
Jan 17 19:53:51 volumio systemd[1]: Stopped Music Player Daemon.
Jan 17 19:53:51 volumio systemd[1]: Starting Music Player Daemon...
Jan 17 19:53:51 volumio volumio[26499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:51 volumio sudo[26644]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 17 19:53:51 volumio volumio[26499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 17 19:53:51 volumio sudo[26644]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:51 volumio volumio[26499]: info: [1737165231159] CoreMusicLibrary::Adding element Last_100
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:51 volumio volumio[26499]: info: [1737165231162] CoreMusicLibrary::Adding element Webradio
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:51 volumio sudo[26644]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:53:51 volumio volumio[26499]: info: Initializing BBC Radios
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:51 volumio volumio[26499]: info: Creating Spotify config file
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:51 volumio volumio[26499]: info: [1737165231372] CoreMusicLibrary::Adding element YouTube Music
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:51 volumio volumio[26499]: Cannot find translation for source YouTube Music
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Controller plugin Registering a thread to poll the NFC reader
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Controller plugin
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Controller plugin
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Controller plugin
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Daemon: Initializing...
Jan 17 19:53:51 volumio volumio[26499]: info: Found PN532 with firmware version:
Jan 17 19:53:51 volumio volumio[26499]: info: NFC Daemon:
Jan 17 19:53:51 volumio volumio[26499]: info: [now-playing] ConfigUpdater: config is up to date.
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:51 volumio volumio[26499]: info: [1737165231481] CoreMusicLibrary::Adding element Randomizer
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:51 volumio volumio[26499]: Cannot find translation for source YouTube Music
Jan 17 19:53:51 volumio volumio[26499]: Cannot find translation for source Randomizer
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:51 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:51 volumio volumio[26499]: error: Plugin remotecontrol failed to start! TypeError: this.commandRouter.volumioGetState(...).then is not a function
Jan 17 19:53:51 volumio volumio[26499]: info: Loading i18n strings for locale en
Jan 17 19:53:51 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:53:51 volumio volumio[26499]: info: Volumio Calling Home
Jan 17 19:53:51 volumio volumio[26499]: info: Preparing to generate the ALSA configuration file
Jan 17 19:53:51 volumio sudo[26667]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Jan 17 19:53:51 volumio sudo[26667]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio sudo[26669]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Jan 17 19:53:51 volumio sudo[26669]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio sudo[26673]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Jan 17 19:53:51 volumio systemd[1]: Reloading.
Jan 17 19:53:51 volumio sudo[26673]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:51 volumio volumio[26499]: info: [now-playing] App is listening on port 4004.
Jan 17 19:53:51 volumio volumio[26499]: info: NFCController started
Jan 17 19:53:51 volumio volumio[26499]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Jan 17 19:53:52 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.
Jan 17 19:53:52 volumio mpd[26651]: config_file: loading file /etc/mpd.conf
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : soxr: soxr converter 'high'
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : vorbis: Xiph.Org libVorbis 1.3.6
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : opus: libopus 1.3
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : sndfile: libsndfile-1.0.28
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : hybrid_dsd: The Hybrid DSD decoder is disabled because it was not explicitly enabled
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : adplug: adplug 2.2.1
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : simple_db: reading DB
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : input: Input plugin 'qobuz' is not configured: No Qobuz app_id configured
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : curl: version 7.64.0
Jan 17 19:53:52 volumio mpd[26651]: Jan 17 19:53 : curl: with GnuTLS/3.6.7
Jan 17 19:53:52 volumio systemd[1]: Started Music Player Daemon.
Jan 17 19:53:52 volumio sudo[26641]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:52 volumio sudo[26669]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:52 volumio sudo[26631]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:52 volumio systemd[1]: Reloading.
Jan 17 19:53:52 volumio sudo[26667]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:52 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.
Jan 17 19:53:52 volumio sudo[26673]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:53 volumio volumio[26499]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Jan 17 19:53:53 volumio volumio[26499]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Jan 17 19:53:53 volumio volumio[26499]: info: Reading ALSA contributions from plugins.
Jan 17 19:53:53 volumio volumio[26499]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/'
Jan 17 19:53:53 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 9
Jan 17 19:53:53 volumio volumio[26499]: info: touch_display: systemctl daemon-reload succeeded.
Jan 17 19:53:53 volumio volumio[26499]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Jan 17 19:53:53 volumio volumio[26499]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Jan 17 19:53:53 volumio sudo[26719]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Jan 17 19:53:53 volumio sudo[26719]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:53 volumio volumio[26499]: info: MPD Permissions set
Jan 17 19:53:53 volumio sudo[26719]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:53 volumio volumio[26499]: info: MPD Permissions set
Jan 17 19:53:54 volumio sudo[26724]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 17 19:53:54 volumio sudo[26724]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:54 volumio sudo[26724]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:54 volumio sudo[26727]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 17 19:53:54 volumio sudo[26727]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:54 volumio sudo[26727]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:54 volumio volumio[26499]: info: MPD running with PID26651
Jan 17 19:53:54 volumio volumio[26499]:  ,establishing connection
Jan 17 19:53:54 volumio volumio[26499]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Jan 17 19:53:54 volumio volumio[26499]: info: touch_display: Volumio Kiosk started.
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:54 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:54 volumio volumio[26499]: info: Volumio called home
Jan 17 19:53:54 volumio volumio[26499]: info: Spotify config file written
Jan 17 19:53:54 volumio sudo[26732]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Jan 17 19:53:54 volumio sudo[26732]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:54 volumio systemd[1]: Stopping go-librespot Daemon...
Jan 17 19:53:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Jan 17 19:53:54 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Jan 17 19:53:54 volumio systemd[1]: Stopped go-librespot Daemon.
Jan 17 19:53:54 volumio volumio[26499]: error: error
Jan 17 19:53:54 volumio systemd[1]: Started go-librespot Daemon.
Jan 17 19:53:54 volumio go-librespot[26734]: Librespot-go daemon starting...
Jan 17 19:53:54 volumio sudo[26732]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=info msg="generated new device id: 630344edb58f5c22dbd8684e3f18d86e340b3e2d"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="stored credentials found for rttgnck"
Jan 17 19:53:54 volumio volumio[26499]: error: MPD error: The expression evaluated to a falsy value:
Jan 17 19:53:54 volumio volumio[26499]:   assert.ok(self.idling)
Jan 17 19:53:54 volumio volumio[26499]: error: The expression evaluated to a falsy value:
Jan 17 19:53:54 volumio volumio[26499]:   assert.ok(self.idling)
Jan 17 19:53:54 volumio volumio[26499]: error: MPD error: The expression evaluated to a falsy value:
Jan 17 19:53:54 volumio volumio[26499]:   assert.ok(self.idling)
Jan 17 19:53:54 volumio volumio[26499]: error: The expression evaluated to a falsy value:
Jan 17 19:53:54 volumio volumio[26499]:   assert.ok(self.idling)
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:54 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:54 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Jan 17 19:53:54 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Jan 17 19:53:54 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:54 volumio volumio[26499]: info: No need to fix Spotify hosts
Jan 17 19:53:54 volumio volumio[26499]: info: touch_display: X display number found: 0
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="zeroconf server listening on port 40461"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="obtained new client token: AAB4Ww2184Au/1F1jqm4jVzDYsukf/wk6P8sCQwMnOob5hPRTsM+7Rb0Nw/8PBrZMVL9sEcXtqVxCy1TP9epLsm5VTZWa5PKxj/xM9HO2T9zKPT7aKNx/T/Skl/2pxrMwg+9TvTsYf8vDtgdfONqdbO+/grPGmVoWQs9fcau+a+v5ZrV9S6BU9YKkRBhQ5pXpTmrTt37DsS1fqYzmm5u1sNsPAGORshXSpqMhFKPpYh3w6MVrsEQ+P/K14Dv"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=info msg="connected to ap-guc3.spotify.com:4070"
Jan 17 19:53:54 volumio go-librespot[26734]: time="2025-01-17T19:53:54-06:00" level=debug msg="completed keyexchange"
Jan 17 19:53:55 volumio go-librespot[26734]: time="2025-01-17T19:53:55-06:00" level=debug msg="completed challenge"
Jan 17 19:53:55 volumio go-librespot[26734]: time="2025-01-17T19:53:55-06:00" level=debug msg="authenticated as rttgnck"
Jan 17 19:53:55 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:53:56 volumio volumio[26499]: error: updateQueue error: null
Jan 17 19:53:56 volumio volumio[26499]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jan 17 19:53:56 volumio volumio[26499]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Jan 17 19:53:56 volumio sudo[26754]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Jan 17 19:53:56 volumio sudo[26754]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio sudo[26754]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio volumio[26499]: info: touch_display: X display number found: 0
Jan 17 19:53:56 volumio volumio[26499]: info: Starting Shairport Sync
Jan 17 19:53:56 volumio volumio[26499]: info: Starting Shairport Sync
Jan 17 19:53:56 volumio sudo[26759]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 17 19:53:56 volumio sudo[26759]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio volumio[26499]: info: Starting Shairport Sync
Jan 17 19:53:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 17 19:53:56 volumio systemd[1]: shairport-sync.service: Succeeded.
Jan 17 19:53:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:56 volumio sudo[26762]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 17 19:53:56 volumio sudo[26762]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio volumio[26499]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Jan 17 19:53:56 volumio volumio[26499]: SPOTIFY: BQC5mKS7BbkBLsvMputvbe7b19YkDhbAGSDpfqc_zVXUdhTzYnlVxgeVG4qJHOB560icVL10H15h0HfdeBEIroRAdN_Rd7BlUbsFpXqDH1mUd_xBNjTSYs-Tlet3VoLJ-WKLqA7Y9NrO8S6jOBYTR3RJ02k-eOANuQKS1nDTnZr-4WaY7JPfMb1S-zhStcZCzEwLGAC4QIPxZfzJOrGMe6SLxjqS2g7sCPfMX2VLMM3o8RrF1oDF6w8
Jan 17 19:53:56 volumio volumio[26499]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Jan 17 19:53:56 volumio volumio[26499]: info: New Spotify access token = BQC5mKS7BbkBLsvMputvbe7b19YkDhbAGSDpfqc_zVXUdhTzYnlVxgeVG4qJHOB560icVL10H15h0HfdeBEIroRAdN_Rd7BlUbsFpXqDH1mUd_xBNjTSYs-Tlet3VoLJ-WKLqA7Y9NrO8S6jOBYTR3RJ02k-eOANuQKS1nDTnZr-4WaY7JPfMb1S-zhStcZCzEwLGAC4QIPxZfzJOrGMe6SLxjqS2g7sCPfMX2VLMM3o8RrF1oDF6w8
Jan 17 19:53:56 volumio volumio[26499]: info: Spotify credentials grant success - running version from March 24, 2019
Jan 17 19:53:56 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:56 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:56 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:56 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:56 volumio volumio[26499]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Jan 17 19:53:56 volumio sudo[26766]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 17 19:53:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:56 volumio sudo[26766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio sudo[26759]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio shairport-sync[26763]: free(): double free detected in tcache 2
Jan 17 19:53:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 17 19:53:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT
Jan 17 19:53:56 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'.
Jan 17 19:53:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:56 volumio sudo[26762]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio sudo[26766]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio volumio[26499]: info: Asound.conf file unchanged, so no further update is needed
Jan 17 19:53:56 volumio volumio[26499]: info: Output device has changed, restarting MPD
Jan 17 19:53:56 volumio sudo[26774]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jan 17 19:53:56 volumio sudo[26774]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio sudo[26774]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio sudo[26777]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 17 19:53:56 volumio sudo[26777]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio sudo[26777]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:56 volumio volumio[26499]: info: Output device has changed, restarting Shairport Sync
Jan 17 19:53:56 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:56 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:56 volumio sudo[26780]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 17 19:53:56 volumio sudo[26780]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:56 volumio systemd[1]: Stopping Music Player Daemon...
Jan 17 19:53:56 volumio systemd[1]: mpd.service: Succeeded.
Jan 17 19:53:56 volumio systemd[1]: Stopped Music Player Daemon.
Jan 17 19:53:56 volumio volumio[26499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 17 19:53:56 volumio volumio[26499]: info: touch_display: Setting screensaver timeout to 120 seconds.
Jan 17 19:53:56 volumio volumio[26499]: info: Shairport-Sync Started
Jan 17 19:53:56 volumio volumio[26499]: Error adding Membership: Error: addMembership EINVAL
Jan 17 19:53:56 volumio volumio[26499]: info: Shairport-Sync Started
Jan 17 19:53:56 volumio volumio[26499]: info: Shairport-Sync Started
Jan 17 19:53:56 volumio systemd[1]: Starting Music Player Daemon...
Jan 17 19:53:57 volumio sudo[26785]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 17 19:53:57 volumio sudo[26785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:57 volumio sudo[26785]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:57 volumio mpd[26791]: config_file: loading file /etc/mpd.conf
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : soxr: soxr converter 'high'
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : vorbis: Xiph.Org libVorbis 1.3.6
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : opus: libopus 1.3
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : sndfile: libsndfile-1.0.28
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : hybrid_dsd: The Hybrid DSD decoder is disabled because it was not explicitly enabled
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : adplug: adplug 2.2.1
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : simple_db: reading DB
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : input: Input plugin 'qobuz' is not configured: No Qobuz app_id configured
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : curl: version 7.64.0
Jan 17 19:53:57 volumio mpd[26791]: Jan 17 19:53 : curl: with GnuTLS/3.6.7
Jan 17 19:53:57 volumio systemd[1]: Started Music Player Daemon.
Jan 17 19:53:57 volumio sudo[26780]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:58 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 13
Jan 17 19:53:58 volumio volumio[26499]: info: MPD Permissions set
Jan 17 19:53:58 volumio volumio[26499]: info: Upmpdcli Daemon Started
Jan 17 19:53:58 volumio volumio[26499]: info: go-librespot daemon successfully initialized
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:58 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:53:59 volumio volumio[26499]: error: updateQueue error: null
Jan 17 19:53:59 volumio volumio[26499]: info: Starting Shairport Sync
Jan 17 19:53:59 volumio sudo[26799]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 17 19:53:59 volumio sudo[26799]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 17 19:53:59 volumio systemd[1]: shairport-sync.service: Succeeded.
Jan 17 19:53:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:59 volumio volumio[26499]: SPOTIFY: User informations: {"country":"US","display_name":"rttgnck","email":"rttgnck@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/rttgnck"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/rttgnck","id":"rttgnck","images":[],"product":"premium","type":"user","uri":"spotify:user:rttgnck"}
Jan 17 19:53:59 volumio volumio[26499]: info: Spotify Successfully logged in
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 17 19:53:59 volumio volumio[26499]: info: [1737165239142] CoreMusicLibrary::Adding element Spotify
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:53:59 volumio volumio[26499]: Cannot find translation for source YouTube Music
Jan 17 19:53:59 volumio volumio[26499]: Cannot find translation for source Randomizer
Jan 17 19:53:59 volumio volumio[26499]: Cannot find translation for source Spotify
Jan 17 19:53:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 17 19:53:59 volumio sudo[26799]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:59 volumio volumio[26499]: info: Shairport-Sync Started
Jan 17 19:53:59 volumio volumio[26499]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Jan 17 19:53:59 volumio volumio[26499]: info: [yt-cast-receiver] DIAL server listening on port 8098
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::volumioRetrievevolume
Jan 17 19:53:59 volumio volumio[26499]: info: VolumeController:: Volume=56 Mute =false
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:53:59 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:59 volumio volumio[26499]: info: CoreStateMachine::pushState
Jan 17 19:53:59 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:53:59 volumio volumio[26499]: info: CoreCommandRouter::volumioPushState
Jan 17 19:53:59 volumio volumio[26499]: info: Completed starting Core Plugins
Jan 17 19:53:59 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:59 volumio volumio[26499]: info: -----    MyVolumio plugins startup     ----
Jan 17 19:53:59 volumio volumio[26499]: info: -------------------------------------------
Jan 17 19:53:59 volumio volumio[26499]: info: [MyVolumio PluginManager] Fetching plans data....
Jan 17 19:53:59 volumio volumio[26499]: info: PeppySpectrum ---peppyspectrum status stop
Jan 17 19:53:59 volumio sudo[26807]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service
Jan 17 19:53:59 volumio sudo[26807]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:53:59 volumio sudo[26807]: pam_unix(sudo:session): session closed for user root
Jan 17 19:53:59 volumio volumio[26499]: info: peppyspectrum Daemon Stop
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="authenticated as rttgnck"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="dealer connection opened"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="initializing zeroconf session, username: rttgnck"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="autoplay enabled: false"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Jan 17 19:54:00 volumio go-librespot[26734]: time="2025-01-17T19:54:00-06:00" level=debug msg="received connection id: Njc4OGQ4MjEtZTJiNy00OWViLTkzYjMtODk3NTFmNzYyMGIzK2RlYWxlcit0Y3A6Ly8wYWNiZDg5OC5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArMERGQ0NENTY4MzYxRTBCM0VFNDUyQzM1MUVCNkMxQzIzNjA4REI3NDA0MkQ5Njg0RTE3NjM5NDc2MzBEQkVEMQ=="
Jan 17 19:54:01 volumio go-librespot[26734]: time="2025-01-17T19:54:01-06:00" level=debug msg="put connect state because NEW_DEVICE"
Jan 17 19:54:01 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:01 volumio volumio[26499]: info: Initializing connection to go-librespot Websocket
Jan 17 19:54:01 volumio go-librespot[26734]: time="2025-01-17T19:54:01-06:00" level=debug msg="new websocket client"
Jan 17 19:54:01 volumio volumio[26499]: info: Connection to go-librespot Websocket established
Jan 17 19:54:04 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 14
Jan 17 19:54:05 volumio volumio[26499]: info: Getting Spotify volume
Jan 17 19:54:05 volumio volumio[26499]: info: Spotify volume: 100
Jan 17 19:54:05 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15
Jan 17 19:54:05 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:05 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:05 volumio volumio[26499]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56
Jan 17 19:54:05 volumio volumio[26499]: SPOTIFY: SPOTIFY VOLUME 100
Jan 17 19:54:05 volumio volumio[26499]: SPOTIFY: VOLUMIO VOLUME 56
Jan 17 19:54:05 volumio volumio[26499]: SPOTIFY: DELTA VOLUME ENOUGH: true
Jan 17 19:54:05 volumio volumio[26499]: info: Setting Spotify Volume from Volumio: 56
Jan 17 19:54:07 volumio volumio[26499]: SPOTIFY: SETTING SPOTIFY VOLUME 56
Jan 17 19:54:07 volumio volumio[26499]: info: Sending Spotify command with payload to local API: /player/volume
Jan 17 19:54:07 volumio go-librespot[26734]: time="2025-01-17T19:54:07-06:00" level=debug msg="update volume to 36699/65535"
Jan 17 19:54:07 volumio go-librespot[26734]: time="2025-01-17T19:54:07-06:00" level=debug msg="put connect state because VOLUME_CHANGED"
Jan 17 19:54:07 volumio go-librespot[26734]: time="2025-01-17T19:54:07-06:00" level=trace msg="emitting websocket event: volume"
Jan 17 19:54:07 volumio volumio[26499]: SPOTIFY: received: {"type":"volume","data":{"value":56,"max":100}}
Jan 17 19:54:07 volumio volumio[26499]: SPOTIFY: RECEIVED SPOTIFY VOLUME 56
Jan 17 19:54:07 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin multiroom to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 17 19:54:07 volumio volumio[26499]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 17 19:54:08 volumio volumio[26499]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 17 19:54:08 volumio volumio[26499]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 17 19:54:08 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:08 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:08 volumio volumio[26499]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 17 19:54:08 volumio volumio[26499]: info: MyVolumio login type: Token
Jan 17 19:54:08 volumio volumio[26499]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 17 19:54:08 volumio volumio[26499]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 17 19:54:08 volumio volumio[26499]: info: Starting Streaming Service Transparent Proxy
Jan 17 19:54:09 volumio volumio[26499]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 17 19:54:09 volumio volumio[26499]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 17 19:54:09 volumio volumio[26499]: info: Streaming services startup
Jan 17 19:54:09 volumio volumio[26499]: info: Starting Streaming Daemon
Jan 17 19:54:09 volumio sudo[26833]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 17 19:54:09 volumio sudo[26833]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:54:09 volumio sudo[26833]: pam_unix(sudo:session): session closed for user root
Jan 17 19:54:09 volumio volumio[26499]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 17 19:54:09 volumio volumio[26499]: STREAMING PROXY: Starting server on port 3245
Jan 17 19:54:09 volumio volumio[26499]: Node JS runtime: 14
Jan 17 19:54:11 volumio volumio[26499]: error: Cannot start Volumio Streaming Daemon
Jan 17 19:54:11 volumio volumio[26499]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 17 19:54:11 volumio volumio[26499]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 17 19:54:11 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 16
Jan 17 19:54:11 volumio volumio[26499]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jan 17 19:54:11 volumio volumio[26499]: info: MyVolumio token set successfully
Jan 17 19:54:11 volumio volumio[26499]: info: MYVOLUMIO: Adding device
Jan 17 19:54:11 volumio volumio[26499]: info: MYVOLUMIO: Evaluating Server
Jan 17 19:54:13 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:14 volumio volumio[26499]: info: MyVolumio status changed
Jan 17 19:54:14 volumio volumio[26499]: info: Streaming services startup
Jan 17 19:54:14 volumio volumio[26499]: info: Starting Streaming Daemon
Jan 17 19:54:14 volumio volumio[26499]: info: Removing browser output: myVolumio user plan is not superstar
Jan 17 19:54:14 volumio volumio[26499]: info: Removing audio output:
Jan 17 19:54:14 volumio volumio[26499]: info: Stoppping Tunnel 1
Jan 17 19:54:14 volumio sudo[26876]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 17 19:54:14 volumio sudo[26876]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:54:14 volumio sudo[26876]: pam_unix(sudo:session): session closed for user root
Jan 17 19:54:14 volumio volumio[26499]: info: Setting Geolocation for MyVolumio to us3
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:14 volumio sudo[26879]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jan 17 19:54:14 volumio sudo[26879]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 17 19:54:14 volumio volumio[26499]: verbose: New Socket.io Connection to 127.0.0.1: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 Engine version: 3 Transport: polling Total Clients: 17
Jan 17 19:54:14 volumio volumio[26499]: error: Cannot start Volumio Streaming Daemon
Jan 17 19:54:14 volumio volumio[26499]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 17 19:54:14 volumio volumio[26499]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 17 19:54:14 volumio sudo[26879]: pam_unix(sudo:session): session closed for user root
Jan 17 19:54:14 volumio volumio[26499]: info: Remote SSH Stopped
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:14 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:14 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 17 19:54:14 volumio volumio[26499]: info: Received Get System Info
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 17 19:54:14 volumio volumio[26499]: info: Discovery: Getting this device information
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:14 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:14 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:14 volumio volumio[26499]: info: Listing playlists
Jan 17 19:54:14 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 17 19:54:14 volumio volumio[26499]: info: Successfully Added MyVolumio device
Jan 17 19:54:15 volumio volumio[26499]: info: Updating MyVolumio device info
Jan 17 19:54:15 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:17 volumio volumio[26499]: info: Successfully Updated MyVolumio device
Jan 17 19:54:19 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:21 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:54:21 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:21 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 17 19:54:21 volumio volumio-remote-updater[653]: Test mode enabled
Jan 17 19:54:21 volumio volumio-remote-updater[653]: No alpha test mode
Jan 17 19:54:21 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 17 19:54:21 volumio volumio[26499]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nImproved translations
\nFix for Allo Piano DAC on RPi with kernel 6.6.x
\n
\nNEW ADDITIONS
\n\nAdd support for Raspberry Pi Display V2 on RPi
\nBump kernel to 6.6.62 on RPi
\nAdd DSD-quirk for Luxman DA-250 on Rivo and Primo2
\nAdd DSD-quirk for Musical Fidelity M6X on Rivo and Primo2
\nAdd DSD-quirk for several USB DACs on RPi
\nEnable install-to-NVME on CM5 and newer RPi5 revisions
\n\n","title":"Update v3.788","updateavailable":true}
Jan 17 19:54:21 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 17 19:54:23 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 17 19:54:23 volumio volumio[26499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12
Jan 17 19:54:23 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:23 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:25 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 17 19:54:28 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 17 19:54:30 volumio volumio[26499]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 17 19:54:30 volumio volumio[26499]: info: Completed starting MyVolumio Plugin
Jan 17 19:54:30 volumio volumio[26499]:  [Metrics]  CommandRouter:  48s 482.53ms 
Jan 17 19:54:30 volumio volumio[26499]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 17 19:54:30 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 17 19:54:30 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 17 19:54:30 volumio volumio[26499]: info: CoreCommandRouter::Close All Modals sent
Jan 17 19:54:30 volumio volumio[26499]: info: CoreCommandRouter::Close All Modals sent
Jan 17 19:54:31 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 17 19:54:31 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 17 19:54:31 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 17 19:54:31 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:38 volumio volumio[26499]: info: BOOT COMPLETED
Jan 17 19:54:38 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:41 volumio volumio[26499]: error: error
Jan 17 19:54:44 volumio wpa_supplicant[916]: RRM: Ignoring radio measurement request: Not RRM network
Jan 17 19:54:46 volumio volumio[26499]: verbose: New Socket.io Connection to 192.168.13.224 from 192.168.13.176 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:46 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 17 19:54:46 volumio volumio[26499]: info: Received Get System Info
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 17 19:54:46 volumio volumio[26499]: info: Discovery: Getting this device information
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:46 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:46 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:46 volumio volumio[26499]: info: Listing playlists
Jan 17 19:54:46 volumio volumio[26499]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 17 19:54:49 volumio volumio[26499]: info: Enabling plugin remotecontrol
Jan 17 19:54:49 volumio volumio[26499]: info: Loading plugin "remotecontrol"...
Jan 17 19:54:49 volumio volumio[26499]: info: Applying required configuration parameters for plugin remotecontrol
Jan 17 19:54:49 volumio volumio[26499]: info: PLUGIN START: remotecontrol
Jan 17 19:54:49 volumio volumio[26499]: info: CoreCommandRouter::volumioGetState
Jan 17 19:54:49 volumio volumio[26499]: info: CorePlayQueue::getTrack 0
Jan 17 19:54:49 volumio volumio[26499]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 17 19:54:49 volumio volumio[26499]: Error: listen EADDRINUSE: address already in use :::16891
Jan 17 19:54:49 volumio volumio[26499]:     at Server.setupListenHandle [as _listen2] (net.js:1318:16)
Jan 17 19:54:49 volumio volumio[26499]:     at listenInCluster (net.js:1366:12)
Jan 17 19:54:49 volumio volumio[26499]:     at Server.listen (net.js:1452:7)
Jan 17 19:54:49 volumio volumio[26499]:     at new WebSocketServer (/data/plugins/user_interface/remotecontrol/node_modules/ws/lib/websocket-server.js:90:20)
Jan 17 19:54:49 volumio volumio[26499]:     at RemoteControlPlugin.onStart (/data/plugins/user_interface/remotecontrol/index.js:31:19)
Jan 17 19:54:49 volumio volumio[26499]:     at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30)
Jan 17 19:54:49 volumio volumio[26499]:     at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Jan 17 19:54:49 volumio volumio[26499]:     at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Jan 17 19:54:49 volumio volumio[26499]:   code: 'EADDRINUSE',
Jan 17 19:54:49 volumio volumio[26499]:   errno: -98,
Jan 17 19:54:49 volumio volumio[26499]:   syscall: 'listen',
Jan 17 19:54:49 volumio volumio[26499]:   address: '::',
Jan 17 19:54:49 volumio volumio[26499]:   port: 16891
Jan 17 19:54:49 volumio volumio[26499]: }
Jan 17 19:54:49 volumio volumio[26499]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 17 19:54:50 volumio sudo[26998]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 19:53
Jan 17 19:54:50 volumio sudo[26998]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 09 Jan 2025 10:53:32 AM CET"
VOLUMIO_VERSION="3.787"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="a8dde41a0a74a6944b1f5d9c8dec1590"