-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-12 17:12:33 CET. -- Jan 12 17:11:01 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:06 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:11 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:16 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:21 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:26 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:31 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:36 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:41 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:46 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:51 volumio-rpi4 volumio[4999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 17 Jan 12 17:11:53 volumio-rpi4 sudo[6081]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio.service Jan 12 17:11:53 volumio-rpi4 sudo[6081]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jan 12 17:11:53 volumio-rpi4 systemd[1]: Stopping Volumio Backend Module... Jan 12 17:11:53 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 12 17:11:53 volumio-rpi4 volumio-remote-updater[602]: [2025-01-12 17:11:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 12 17:11:53 volumio-rpi4 volumio-remote-updater[602]: [2025-01-12 17:11:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 12 17:11:53 volumio-rpi4 vtcs[5489]: [2025-01-12 17:11:53.603] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jan 12 17:11:53 volumio-rpi4 systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Jan 12 17:11:53 volumio-rpi4 systemd[1]: volumio.service: Succeeded. Jan 12 17:11:53 volumio-rpi4 go-librespot[5255]: time="2025-01-12T17:11:53+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 12 17:11:53 volumio-rpi4 systemd[1]: Stopped Volumio Backend Module. Jan 12 17:11:53 volumio-rpi4 sudo[6081]: pam_unix(sudo:session): session closed for user root Jan 12 17:11:53 volumio-rpi4 sudo[6084]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl start volumio.service Jan 12 17:11:53 volumio-rpi4 sudo[6084]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jan 12 17:11:53 volumio-rpi4 systemd[1]: Started dynamicswap service. Jan 12 17:11:53 volumio-rpi4 systemd[1]: Started Volumio Backend Module. Jan 12 17:11:53 volumio-rpi4 sudo[6084]: pam_unix(sudo:session): session closed for user root Jan 12 17:11:53 volumio-rpi4 systemd[1]: dynamicswap.service: Succeeded. Jan 12 17:11:54 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:11:54 volumio-rpi4 volumio[6088]: info: ----- Volumio3 ---- Jan 12 17:11:54 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:11:54 volumio-rpi4 volumio[6088]: info: ----- System startup ---- Jan 12 17:11:54 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: MYVOLUMIO Environment detected Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Plugin folders cleanup Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning into folder /volumio/app/plugins/ Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category audio_interface Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category miscellanea Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category music_service Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category plugins.json Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category system_controller Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category user_interface Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning into folder /data/plugins/ Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category audio_interface Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category music_service Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category system_controller Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Scanning category user_interface Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Plugin folders cleanup completed Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: ----- Core plugins startup ---- Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugins from folder /volumio/app/plugins/ Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Adding plugin upnp to MyMusic Plugins Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugins from folder /data/plugins/ Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugin "system"... Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugin "appearance"... Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugin "network"... Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Refreshing Cached IP Addresses Jan 12 17:11:55 volumio-rpi4 sudo[6115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 17:11:55 volumio-rpi4 sudo[6115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:11:55 volumio-rpi4 sudo[6117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 17:11:55 volumio-rpi4 sudo[6117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:11:55 volumio-rpi4 sudo[6115]: pam_unix(sudo:session): session closed for user root Jan 12 17:11:55 volumio-rpi4 sudo[6117]: pam_unix(sudo:session): session closed for user root Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugin "services"... Jan 12 17:11:55 volumio-rpi4 volumio[6088]: info: Loading plugin "alsa_controller"... Jan 12 17:11:55 volumio-rpi4 sudo[6126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 12 17:11:55 volumio-rpi4 sudo[6126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "wizard"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "networkfs"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Cannot mount NAS musique at system boot, trial number 1 ,retrying in 5 seconds Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Starting Udev Watcher for removable devices Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Ignoring mount for partition: boot Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Ignoring mount for partition: volumio Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Ignoring mount for partition: volumio_data Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "volumio_command_line_client"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "upnp"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: [1736698316110] Starting Upmpd Daemon Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "my_music"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "mpd"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "upnp_browser"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "alarm-clock"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "airplay_emulation"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "last_100"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "webradio"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "i2s_dacs"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: I2S DAC not set, start Auto-detection Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "volumiodiscovery"... Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** For more information see Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 17:11:56 volumio-rpi4 volumio[6088]: *** WARNING *** For more information see Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** For more information see Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 17:11:56 volumio-rpi4 node[6088]: *** WARNING *** For more information see Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Discovery: Started advertising with name: Volumio-rpi4 Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 17:11:56 volumio-rpi4 volumio[6088]: info: Loading plugin "calmradio"... Jan 12 17:11:57 volumio-rpi4 volumio[6088]: info: Loading plugin "spop"... Jan 12 17:11:57 volumio-rpi4 volumio[6088]: info: Loading plugin "ytmusic"... Jan 12 17:11:58 volumio-rpi4 volumio-remote-updater[602]: [2025-01-12 17:11:58] [connect] Successful connection Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "SleepWakePlugin"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Applying required configuration parameters for plugin SleepWakePlugin Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: SleepWakePlugin - onVolumioStart Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Plugin now_playing is not enabled Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "outputs"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "albumart"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Plugin example_plugin is not enabled Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "inputs"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "updater_comm"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Plugin mpdemulation is not enabled Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "rest_api"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "websocket"... Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Starting Socket.io Server version 2.3.0 Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Plugin Bluetoothout is not enabled Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Loading plugin "fusiondsp"... Jan 12 17:11:59 volumio-rpi4 sudo[6126]: pam_unix(sudo:session): session closed for user root Jan 12 17:11:59 volumio-rpi4 volumio[6088]: Forking 3 albumart workers Jan 12 17:11:59 volumio-rpi4 volumio[6088]: info: Applying required configuration parameters for plugin fusiondsp Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Loading plugin "mpdoutput"... Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Loading plugin "podcast"... Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: ControllerPodcast::constructor Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Plugin peppymeterbasic is not enabled Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Plugin peppyspectrum is not enabled Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Loading plugin "touch_display"... Jan 12 17:12:00 volumio-rpi4 volumio[6088]: Starting albumart workers Jan 12 17:12:00 volumio-rpi4 volumio[6088]: Starting albumart workers Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Applying required configuration parameters for plugin touch_display Jan 12 17:12:00 volumio-rpi4 volumio[6088]: Starting albumart workers Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Loading i18n strings for locale en Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jan 12 17:12:00 volumio-rpi4 volumio[6088]: Updating browse sources language Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::initPlayerControls Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 17:12:00 volumio-rpi4 volumio[6088]: Express server listening on port 3000 Jan 12 17:12:00 volumio-rpi4 volumio[6088]: [Metrics] WebUI: 6s 300.46ms Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreStateMachine::resetVolumioState Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreStateMachine::getcurrentVolume Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:00 volumio-rpi4 volumio[6088]: info: Volumio Network Manager: Network status updated: 1 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.77 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: 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: 2 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: 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: 5 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.77 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.38 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 12 17:12:01 volumio-rpi4 volumio-remote-updater[602]: [2025-01-12 17:12:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736698318 101 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: 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: 9 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Cannot mount NAS musique at system boot, trial number 2 ,retrying in 5 seconds Jan 12 17:12:01 volumio-rpi4 volumio[6088]: 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 12 17:12:01 volumio-rpi4 volumio[6088]: info: Reloading queue from file Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 11 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 12 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: VolumeController:: Volume=25 Mute =false Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::updateTrackBlock Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrackBlock Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::setRepeat null single undefined Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::setRandom true Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Setting Device type: Raspberry PI Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 13 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 14 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 15 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: VolumeController:: Volume=25 Mute =false Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: adding 89a86619-18ae-4300-a9bd-067abcfbd7d0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Found device Volumio-primo Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connecting to remote: 192.168.1.77 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: adding c8d9e3d6-fa93-49b0-9dc2-577f68689abf Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Found device Integro-office Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connecting to remote: 192.168.1.38 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Completed loading Core Plugins Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Preparing to generate the ALSA configuration file Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Received Get System Info Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Getting this device information Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Received Get System Info Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Getting this device information Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Listing playlists Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Reading ALSA contributions from plugins. Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: adding b1a570d3-0924-4981-8fd1-68bca66a4193 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Found device bookworm-rpi5 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connecting to remote: 192.168.1.23 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: adding cb9136bd-5e70-4c85-86fa-eff4cd2bf0ca Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Found device Volumio-rpi4 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connected to remote: 192.168.1.77 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connected to remote: 192.168.1.38 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: this is already registered, cb9136bd-5e70-4c85-86fa-eff4cd2bf0ca Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Found device Volumio-rpi4 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Discovery: Connected to remote: 192.168.1.23 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Asound.conf file unchanged, so no further update is needed Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting MPD Jan 12 17:12:01 volumio-rpi4 sudo[6218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 17:12:01 volumio-rpi4 sudo[6218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting Shairport Sync Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:01 volumio-rpi4 sudo[6218]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:01 volumio-rpi4 sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 17:12:01 volumio-rpi4 sudo[6221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:01 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: ___________ START PLUGINS ___________ Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: ControllerMpd::onStart: Initializing MPD Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Creating MPD Configuration file Jan 12 17:12:01 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 12 17:12:01 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 12 17:12:01 volumio-rpi4 sudo[6229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 17:12:01 volumio-rpi4 sudo[6229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: [1736698321910] CoreMusicLibrary::Adding element Media Servers Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:01 volumio-rpi4 sudo[6229]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:01 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 12 17:12:01 volumio-rpi4 sudo[6232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 17:12:01 volumio-rpi4 sudo[6232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:01 volumio-rpi4 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 12 17:12:01 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 12 17:12:01 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 12 17:12:01 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:01 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: [1736698322024] CoreMusicLibrary::Adding element Last_100 Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: [1736698322026] CoreMusicLibrary::Adding element Webradio Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Initializing BBC Radios Jan 12 17:12:02 volumio-rpi4 sudo[6237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 17:12:02 volumio-rpi4 sudo[6237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:02 volumio-rpi4 sudo[6237]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Adding Calm Radio to Browse Sources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: [1736698322113] CoreMusicLibrary::Adding element Calm Radio Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source Calm Radio Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Creating Spotify config file Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: [1736698322133] CoreMusicLibrary::Adding element YouTube Music Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source Calm Radio Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source YouTube Music Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: SleepWakePlugin - onStart Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: SleepWakePlugin - Sleep scheduled in 24537860 milliseconds Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: SleepWakePlugin - Wake scheduled in 46077857 milliseconds Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Loading i18n strings for locale en Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - mixtype--------------------- Hardware Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: [1736698322163] CoreMusicLibrary::Adding element Podcast Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source Calm Radio Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source YouTube Music Jan 12 17:12:02 volumio-rpi4 volumio[6088]: Cannot find translation for source Podcast Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Loading i18n strings for locale en Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Volumio Calling Home Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: Preparing to generate the ALSA configuration file Jan 12 17:12:02 volumio-rpi4 sudo[6257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 12 17:12:02 volumio-rpi4 sudo[6257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:02 volumio-rpi4 sudo[6260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 12 17:12:02 volumio-rpi4 sudo[6260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:02 volumio-rpi4 systemd[1]: Reloading. Jan 12 17:12:02 volumio-rpi4 sudo[6263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 12 17:12:02 volumio-rpi4 sudo[6263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:02 volumio-rpi4 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 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:02 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:03 volumio-rpi4 mpd[6244]: Jan 12 17:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 17:12:03 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 12 17:12:03 volumio-rpi4 sudo[6260]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 sudo[6221]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 sudo[6232]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 sudo[6257]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 systemd[1]: Reloading. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Reading ALSA contributions from plugins. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: touch_display: Backlight interface detected. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:03 volumio-rpi4 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 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: 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 12 17:12:03 volumio-rpi4 volumio[6088]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: MPD Permissions set Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: MPD Permissions set Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Volumio called home Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Spotify config file written Jan 12 17:12:03 volumio-rpi4 sudo[6298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 12 17:12:03 volumio-rpi4 sudo[6298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 volumio[6088]: error: MPD error: The expression evaluated to a falsy value: Jan 12 17:12:03 volumio-rpi4 volumio[6088]: assert.ok(self.idling) Jan 12 17:12:03 volumio-rpi4 volumio[6088]: error: The expression evaluated to a falsy value: Jan 12 17:12:03 volumio-rpi4 volumio[6088]: assert.ok(self.idling) Jan 12 17:12:03 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: MPD running with PID6244 Jan 12 17:12:03 volumio-rpi4 volumio[6088]: ,establishing connection Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: No need to fix Spotify hosts Jan 12 17:12:03 volumio-rpi4 volumio[6088]: error: updateQueue error: null Jan 12 17:12:03 volumio-rpi4 sudo[6263]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 systemd[1]: Stopping go-librespot Daemon... Jan 12 17:12:03 volumio-rpi4 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 12 17:12:03 volumio-rpi4 systemd[1]: go-librespot-daemon.service: Succeeded. Jan 12 17:12:03 volumio-rpi4 systemd[1]: Stopped go-librespot Daemon. Jan 12 17:12:03 volumio-rpi4 systemd[1]: Started go-librespot Daemon. Jan 12 17:12:03 volumio-rpi4 sudo[6298]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 go-librespot[6308]: Librespot-go daemon starting... Jan 12 17:12:03 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:03+01:00" level=info msg="generated new device id: d732b977e19f0fe31157bf1d9df88e58ae238a3d" Jan 12 17:12:03 volumio-rpi4 sudo[6316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Jan 12 17:12:03 volumio-rpi4 sudo[6316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 sudo[6316]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 12 17:12:03 volumio-rpi4 volumio[6088]: error: updateQueue error: null Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: touch_display: systemctl daemon-reload succeeded. Jan 12 17:12:03 volumio-rpi4 sudo[6319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 12 17:12:03 volumio-rpi4 sudo[6319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 sudo[6319]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:12:03 volumio-rpi4 sudo[6322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 12 17:12:03 volumio-rpi4 sudo[6322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:12:03 volumio-rpi4 sudo[6325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 17:12:03 volumio-rpi4 sudo[6325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:12:03 volumio-rpi4 sudo[6322]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:03 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 17:12:03 volumio-rpi4 sudo[6328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 17:12:03 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 12 17:12:03 volumio-rpi4 sudo[6328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:03 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:03 volumio-rpi4 sudo[6332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 17:12:03 volumio-rpi4 sudo[6332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: File permissions for backlight brightness control set. Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: Volumio Kiosk started. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:04 volumio-rpi4 sudo[6325]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Completed starting Core Plugins Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: ----- MyVolumio plugins startup ---- Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: ------------------------------------------- Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Asound.conf file unchanged, so no further update is needed Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting MPD Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 17:12:04 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:04 volumio-rpi4 sudo[6341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 17:12:04 volumio-rpi4 sudo[6341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 sudo[6328]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 sudo[6332]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 sudo[6341]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting Shairport Sync Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:04 volumio-rpi4 sudo[6345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 17:12:04 volumio-rpi4 sudo[6345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 17:12:04 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Shairport-Sync Started Jan 12 17:12:04 volumio-rpi4 volumio[6088]: Error adding Membership: Error: addMembership EINVAL Jan 12 17:12:04 volumio-rpi4 sudo[6353]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 17:12:04 volumio-rpi4 sudo[6353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 sudo[6353]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 volumio[6088]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 12 17:12:04 volumio-rpi4 volumio[6088]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 12 17:12:04 volumio-rpi4 volumio[6088]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: camilladsp service started and running in background, instance 1 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 12 17:12:04 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:04+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 12 17:12:04 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:04+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 12 17:12:04 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:04+01:00" level=debug msg="zeroconf server listening on port 37483" Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp loaded Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:12:04 volumio-rpi4 sudo[6372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 12 17:12:04 volumio-rpi4 sudo[6372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Adding Signal Path Element [object Object] Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Adding fusiondspeq DSP Signal Path Element Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp - ---- installed callbackRead Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 17:12:04 volumio-rpi4 sudo[6372]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: MPD Permissions set Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Shairport-Sync Started Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Shairport-Sync Started Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: X display number found: 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 18 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: X display number found: 0 Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 12 17:12:04 volumio-rpi4 sudo[6387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 17:12:04 volumio-rpi4 sudo[6387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 17:12:04 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:04 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:04 volumio-rpi4 sudo[6387]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:04 volumio-rpi4 volumio[6088]: info: Shairport-Sync Started Jan 12 17:12:05 volumio-rpi4 mpd[6359]: Jan 12 17:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 17:12:05 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 12 17:12:05 volumio-rpi4 sudo[6345]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:05 volumio-rpi4 volumio[6088]: error: updateQueue error: null Jan 12 17:12:05 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:05 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 19 Jan 12 17:12:06 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:06 volumio-rpi4 volumio[6088]: info: Cannot mount NAS musique at system boot, trial number 3 ,retrying in 5 seconds Jan 12 17:12:06 volumio-rpi4 sudo[6409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 17:12:06 volumio-rpi4 sudo[6409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:06 volumio-rpi4 sudo[6409]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:06 volumio-rpi4 sudo[6412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 17:12:06 volumio-rpi4 sudo[6412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:06 volumio-rpi4 sudo[6412]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:06 volumio-rpi4 sudo[6415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 17:12:06 volumio-rpi4 sudo[6415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:06 volumio-rpi4 sudo[6415]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:06 volumio-rpi4 volumio[6088]: info: Upmpdcli Daemon Started Jan 12 17:12:06 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:06 volumio-rpi4 volumio[6088]: info: go-librespot daemon successfully initialized Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:07 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 20 Jan 12 17:12:07 volumio-rpi4 volumio[6088]: 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: 21 Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:07 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: Received Get System Info Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: Discovery: Getting this device information Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: Listing playlists Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Selected DSP label: undefined Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - ncontent: 15 Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:08 volumio-rpi4 volumio[6088]: info: FusionDsp - Convolution filters available. Jan 12 17:12:08 volumio-rpi4 volumio[6088]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 17:12:09 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:09 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:09 volumio-rpi4 volumio[6088]: info: Initializing connection to go-librespot Websocket Jan 12 17:12:10 volumio-rpi4 go-librespot[6308]: time="2025-01-12T17:12:10+01:00" level=debug msg="new websocket client" Jan 12 17:12:10 volumio-rpi4 volumio[6088]: info: Connection to go-librespot Websocket established Jan 12 17:12:10 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:10 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 22 Jan 12 17:12:10 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:11 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 17:12:11 volumio-rpi4 volumio[6088]: info: Cannot mount NAS musique at system boot, trial number 4 ,retrying in 5 seconds Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Getting Spotify volume Jan 12 17:12:13 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: SPOTIFY: SPOTIFY VOLUME undefined Jan 12 17:12:13 volumio-rpi4 volumio[6088]: SPOTIFY: VOLUMIO VOLUME 25 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Aligning Spotify Volume to Volumio Volume Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Setting Spotify Volume from Volumio: 25 Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: MyVolumio login type: Token Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 17:12:13 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: Starting Streaming Service Transparent Proxy Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: Streaming services startup Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: Starting Streaming Daemon Jan 12 17:12:14 volumio-rpi4 sudo[6432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 17:12:14 volumio-rpi4 sudo[6432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:14 volumio-rpi4 sudo[6432]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 17:12:14 volumio-rpi4 volumio[6088]: error: Cannot start Volumio Streaming Daemon Jan 12 17:12:14 volumio-rpi4 volumio[6088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 17:12:14 volumio-rpi4 volumio[6088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 17:12:14 volumio-rpi4 volumio[6088]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Jan 12 17:12:14 volumio-rpi4 volumio[6088]: info: Sending Spotify command with payload to local API: /player/volume Jan 12 17:12:14 volumio-rpi4 volumio[6088]: STREAMING PROXY: Starting server on port 3245 Jan 12 17:12:14 volumio-rpi4 volumio[6088]: Node JS runtime: 14 Jan 12 17:12:15 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 24 Jan 12 17:12:15 volumio-rpi4 volumio[6088]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: MyVolumio token set successfully Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: MYVOLUMIO: Adding device Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: MYVOLUMIO: Evaluating Server Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: MyVolumio Plan changed: superstar Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jan 12 17:12:16 volumio-rpi4 volumio[6088]: info: Adding audio output: Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: MYVOLUMIO: Adding device Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: MYVOLUMIO: Evaluating Server Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Remote config written successfully Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Starting Tunnel 1 Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Starting Tunnel Connection Checker Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Completed starting MyVolumio Plugin Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: MYVolumio Device enabled Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: MyVolumio status changed Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Streaming services startup Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: Starting Streaming Daemon Jan 12 17:12:17 volumio-rpi4 sudo[6486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 17:12:17 volumio-rpi4 sudo[6486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 17:12:17 volumio-rpi4 sudo[6486]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: [1736698337836] Starting BluetoothController Jan 12 17:12:17 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 12 17:12:18 volumio-rpi4 sudo[6489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 17:12:18 volumio-rpi4 sudo[6489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:18 volumio-rpi4 sudo[6489]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:18 volumio-rpi4 volumio[6088]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: MRS: MultiRoom plugin initialized Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: MRS: STOPPING SNAPCLIENT Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: MRS: Snap server stop Jan 12 17:12:18 volumio-rpi4 sudo[6505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 17:12:18 volumio-rpi4 sudo[6505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: MRS: STOPPING volumioStreaming Jan 12 17:12:18 volumio-rpi4 sudo[6508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 17:12:18 volumio-rpi4 sudo[6508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:18 volumio-rpi4 sudo[6505]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:18 volumio-rpi4 sudo[6511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 17:12:18 volumio-rpi4 sudo[6511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:18 volumio-rpi4 sudo[6508]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 17:12:18 volumio-rpi4 sudo[6514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 17:12:18 volumio-rpi4 sudo[6514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:18 volumio-rpi4 sudo[6514]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 17:12:18 volumio-rpi4 sudo[6511]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: Preparing to generate the ALSA configuration file Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: Reading ALSA contributions from plugins. Jan 12 17:12:18 volumio-rpi4 volumio[6088]: info: Setting Geolocation for MyVolumio to eu3 Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Removed streaming files Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: volumioStreaming STOPPED Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: SNAPSERVER STOPPED Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: SNAPCLIENT STOPPED Jan 12 17:12:19 volumio-rpi4 volumio[6088]: error: Cannot start Volumio Streaming Daemon Jan 12 17:12:19 volumio-rpi4 volumio[6088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 17:12:19 volumio-rpi4 volumio[6088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Setting Geolocation for MyVolumio to eu2 Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Asound.conf file unchanged, so no further update is needed Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting MPD Jan 12 17:12:19 volumio-rpi4 sudo[6520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 17:12:19 volumio-rpi4 sudo[6520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 sudo[6520]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Output device has changed, restarting Shairport Sync Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 sudo[6523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 17:12:19 volumio-rpi4 sudo[6523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 17:12:19 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output for this device Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output Jan 12 17:12:19 volumio-rpi4 sudo[6532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-rpi4 Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding audio output: Jan 12 17:12:19 volumio-rpi4 sudo[6532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding audio output: Jan 12 17:12:19 volumio-rpi4 sudo[6532]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding METAVOLUMIO REST API Endpoints Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: error: Hi Res Audio Failed Login: Missing Login Data Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Refreshing QOBUZ token Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 12 17:12:19 volumio-rpi4 sudo[6528]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 17:12:19 volumio-rpi4 sudo[6528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 sudo[6528]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:19 volumio-rpi4 sudo[6540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 17:12:19 volumio-rpi4 sudo[6540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Stopping AccessToken refresher cron Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopping Volumio Tidal Connect Service... Jan 12 17:12:19 volumio-rpi4 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 12 17:12:19 volumio-rpi4 systemd[1]: vtcs.service: Succeeded. Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopped Volumio Tidal Connect Service. Jan 12 17:12:19 volumio-rpi4 sudo[6540]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: AccessToken refresher cron started Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding TIDAL REST API Endpoints Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Adding multiroomSync output Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding audio output: Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Bluetooth name changed to Volumio-rpi4 Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: MPD Permissions set Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Starting Shairport Sync Jan 12 17:12:19 volumio-rpi4 sudo[6548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 17:12:19 volumio-rpi4 sudo[6548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 17:12:19 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 12 17:12:19 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:19 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 17:12:19 volumio-rpi4 sudo[6548]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Shairport-Sync Started Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Access Token successfully retrieved Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: [1736698339701] CoreMusicLibrary::Adding element QOBUZ Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 17:12:19 volumio-rpi4 volumio[6088]: Cannot find translation for source Calm Radio Jan 12 17:12:19 volumio-rpi4 volumio[6088]: Cannot find translation for source YouTube Music Jan 12 17:12:19 volumio-rpi4 volumio[6088]: Cannot find translation for source Podcast Jan 12 17:12:19 volumio-rpi4 volumio[6088]: Cannot find translation for source QOBUZ Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Stopping AccessToken refresher cron for QOBUZ Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: AccessToken refresher cron started for QOBUZ Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding QOBUZ REST API Endpoints Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Successfully Added MyVolumio device Jan 12 17:12:19 volumio-rpi4 volumio[6088]: info: Successfully Added MyVolumio device Jan 12 17:12:19 volumio-rpi4 mpd[6539]: Jan 12 17:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: Updating MyVolumio device info Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: Updating MyVolumio device info Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:20 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 12 17:12:20 volumio-rpi4 sudo[6523]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:20 volumio-rpi4 volumio[6088]: error: updateQueue error: null Jan 12 17:12:20 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: Successfully Updated MyVolumio device Jan 12 17:12:20 volumio-rpi4 volumio[6088]: info: Successfully Updated MyVolumio device Jan 12 17:12:22 volumio-rpi4 sudo[6561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 17:12:22 volumio-rpi4 sudo[6561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:22 volumio-rpi4 autossh[5471]: received signal to exit (15) Jan 12 17:12:22 volumio-rpi4 systemd[1]: Stopping MyVolumio SSH Tunnel... Jan 12 17:12:22 volumio-rpi4 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jan 12 17:12:22 volumio-rpi4 systemd[1]: sshtunnel.service: Succeeded. Jan 12 17:12:22 volumio-rpi4 systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 17:12:22 volumio-rpi4 systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 17:12:22 volumio-rpi4 sudo[6565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 12 17:12:22 volumio-rpi4 sudo[6565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:22 volumio-rpi4 sudo[6561]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:22 volumio-rpi4 autossh[6566]: port set to 0, monitoring disabled Jan 12 17:12:22 volumio-rpi4 autossh[6566]: starting ssh (count 1) Jan 12 17:12:22 volumio-rpi4 autossh[6566]: ssh child pid is 6570 Jan 12 17:12:22 volumio-rpi4 sudo[6565]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Volumio BT Module successfully started Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Remote SSH Started Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: TidalConnect service stoped! Jan 12 17:12:22 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:22 volumio-rpi4 sudo[6580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 17:12:22 volumio-rpi4 sudo[6580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 17:12:22 volumio-rpi4 systemd[1]: Started Volumio Tidal Connect Service. Jan 12 17:12:22 volumio-rpi4 volumiossh-tunnel[6563]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Jan 12 17:12:22 volumio-rpi4 sudo[6580]: pam_unix(sudo:session): session closed for user root Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Executing endpoint tc_getconfig Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 12 17:12:22 volumio-rpi4 vtcs[6582]: STARTING TidalConnect services, version: 1.4.0.34 Jan 12 17:12:22 volumio-rpi4 vtcs[6582]: STARTED TidalConnect services. Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Executing endpoint tc_connect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Connecting to TidalConnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::servicePushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::servicePushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 12 17:12:22 volumio-rpi4 volumio[6088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jan 12 17:12:22 volumio-rpi4 volumio[6088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jan 12 17:12:24 volumio-rpi4 volumio[6088]: info: MRS: Getting audio outputs on start Jan 12 17:12:24 volumio-rpi4 volumio[6088]: info: MRS: Requesting all other devices output Jan 12 17:12:25 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 27 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: TidalConnect service started! Jan 12 17:12:25 volumio-rpi4 volumio[6088]: [Metrics] CommandRouter: 30s 897.95ms Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: VolumeController:: Setting startup Volume 25 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: VolumeController::SetAlsaVolume25 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::Close All Modals sent Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::Close All Modals sent Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreStateMachine::pushState Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioPushState Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: MRS: Pushing multiroomSync output Jan 12 17:12:25 volumio-rpi4 volumio[6088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: camilladsp stopping service pid 6362... Jan 12 17:12:25 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: camilladsp service terminated, instance 1 Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 17:12:25 volumio-rpi4 volumio[6088]: info: camilladsp service started and running in background, instance 1 Jan 12 17:12:26 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 17:12:26 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 17:12:26 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 17:12:28 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 17:12:28 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Jan 12 17:12:28 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::volumioGetState Jan 12 17:12:28 volumio-rpi4 volumio[6088]: info: CorePlayQueue::getTrack 0 Jan 12 17:12:30 volumio-rpi4 volumio[6088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 29 Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 17:12:32 volumio-rpi4 volumio-remote-updater[602]: Test mode enabled Jan 12 17:12:32 volumio-rpi4 volumio-remote-updater[602]: No alpha test mode Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 17:12:32 volumio-rpi4 volumio[6088]: info: BOOT COMPLETED Jan 12 17:12:32 volumio-rpi4 volumio[6088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:12:32 volumio-rpi4 volumio[6088]: ReferenceError: self is not defined Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Interface. (/data/plugins/audio_interface/mpdoutput/index.js:283:13) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Interface.emit (events.js:315:20) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Interface._onLine (readline.js:337:10) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Interface._normalWrite (readline.js:482:12) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Socket.ondata (readline.js:194:10) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Socket.emit (events.js:315:20) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at addChunk (internal/streams/readable.js:309:12) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jan 12 17:12:32 volumio-rpi4 volumio[6088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:12:33 volumio-rpi4 sudo[6654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 17:11 Jan 12 17:12:33 volumio-rpi4 sudo[6654]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="d316d397153fbb98c246d670c12f98a120b995a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 11 Jan 2025 10:58:50 AM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e6f1869148b01453dc48d3e2d79d1421"