-- Logs begin at Fri 2021-10-01 13:11:44 UTC, end at Mon 2025-06-30 09:22:17 UTC. -- Jun 30 09:21:03 musico systemd[1]: Time has been changed Jun 30 09:21:04 musico firststart.sh[1095]: Removing default SSH host keys Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_dsa_key' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_dsa_key.pub' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_ecdsa_key' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_ed25519_key' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_rsa_key' Jun 30 09:21:04 musico firststart.sh[1095]: removed '/etc/ssh/ssh_host_rsa_key.pub' Jun 30 09:21:04 musico firststart.sh[1095]: Generating SSH host keys Jun 30 09:21:07 musico firststart.sh[1095]: debconf: unable to initialize frontend: Dialog Jun 30 09:21:07 musico firststart.sh[1095]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jun 30 09:21:07 musico firststart.sh[1095]: debconf: falling back to frontend: Readline Jun 30 09:21:07 musico firststart.sh[1095]: debconf: unable to initialize frontend: Readline Jun 30 09:21:07 musico firststart.sh[1095]: debconf: (This frontend requires a controlling tty.) Jun 30 09:21:07 musico firststart.sh[1095]: debconf: falling back to frontend: Teletype Jun 30 09:21:08 musico volumio[1094]: info: ------------------------------------------- Jun 30 09:21:08 musico volumio[1094]: info: ----- Volumio2 ---- Jun 30 09:21:08 musico volumio[1094]: info: ------------------------------------------- Jun 30 09:21:08 musico volumio[1094]: info: ----- System startup ---- Jun 30 09:21:08 musico volumio[1094]: info: ------------------------------------------- Jun 30 09:21:09 musico volumio-remote-updater[782]: [2025-06-30 09:21:09] [connect] Successful connection Jun 30 09:21:10 musico volumio[1094]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Jun 30 09:21:10 musico volumio[1094]: info: Plugin folders cleanup Jun 30 09:21:10 musico volumio[1094]: info: Scanning into folder /volumio/app/plugins/ Jun 30 09:21:10 musico volumio[1094]: info: Scanning category audio_interface Jun 30 09:21:10 musico volumio[1094]: info: Scanning category miscellanea Jun 30 09:21:10 musico volumio[1094]: info: Scanning category music_service Jun 30 09:21:10 musico volumio[1094]: info: Scanning category plugins.json Jun 30 09:21:10 musico volumio[1094]: info: Scanning category system_controller Jun 30 09:21:10 musico volumio[1094]: info: Scanning category user_interface Jun 30 09:21:10 musico volumio[1094]: info: Scanning into folder /data/plugins/ Jun 30 09:21:10 musico volumio[1094]: info: Plugin folders cleanup completed Jun 30 09:21:10 musico volumio[1094]: info: ------------------------------------------- Jun 30 09:21:10 musico volumio[1094]: info: ----- Core plugins startup ---- Jun 30 09:21:10 musico volumio[1094]: info: ------------------------------------------- Jun 30 09:21:10 musico volumio[1094]: info: Loading plugins from folder /volumio/app/plugins/ Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin bluetooth to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin upnp to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin cd_controller to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Adding plugin volspotconnect2 to MyMusic Plugins Jun 30 09:21:10 musico volumio[1094]: info: Loading plugins from folder /data/plugins/ Jun 30 09:21:10 musico volumio[1094]: info: Loading plugin "system"... Jun 30 09:21:10 musico volumio[1094]: No id defined. Creating one Jun 30 09:21:10 musico volumio[1094]: info: Loading plugin "appearance"... Jun 30 09:21:11 musico firststart.sh[1095]: Creating SSH2 RSA key; this may take some time ... Jun 30 09:21:11 musico firststart.sh[1095]: 2048 c2:1c:41:4f:c5:bc:cb:65:4d:08:5e:b0:8c:18:12:3a /etc/ssh/ssh_host_rsa_key.pub (RSA) Jun 30 09:21:11 musico volumio[1094]: info: Loading plugin "network"... Jun 30 09:21:11 musico volumio[1094]: info: Refreshing Cached IP Addresses Jun 30 09:21:11 musico sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 09:21:11 musico volumio[1094]: info: Loading plugin "services"... Jun 30 09:21:11 musico volumio[1094]: info: Loading plugin "alsa_controller"... Jun 30 09:21:11 musico sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:11 musico sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 09:21:11 musico sudo[1250]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:11 musico sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:11 musico sudo[1252]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:12 musico volumio[1094]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "wizard"... Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "volumio_command_line_client"... Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "upnp"... Jun 30 09:21:12 musico volumio[1094]: info: [1751275272123] Starting Upmpd Daemon Jun 30 09:21:12 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "my_music"... Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "mpd"... Jun 30 09:21:12 musico volumio[1094]: info: Loading plugin "upnp_browser"... Jun 30 09:21:13 musico volumio[1094]: info: Loading plugin "networkfs"... Jun 30 09:21:13 musico firststart.sh[1095]: Creating SSH2 DSA key; this may take some time ... Jun 30 09:21:13 musico firststart.sh[1095]: 1024 17:28:e5:e9:5b:8c:0d:c7:56:7a:81:b1:0a:80:71:59 /etc/ssh/ssh_host_dsa_key.pub (DSA) Jun 30 09:21:13 musico firststart.sh[1095]: Creating SSH2 ECDSA key; this may take some time ... Jun 30 09:21:13 musico firststart.sh[1095]: 256 ad:7c:3e:ae:4f:af:97:5c:20:3a:c4:2c:16:f6:0b:16 /etc/ssh/ssh_host_ecdsa_key.pub (ECDSA) Jun 30 09:21:14 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 09:21:14 musico volumio[1094]: info: Loading plugin "bluetooth"... Jun 30 09:21:14 musico volumio[1094]: info: [1751275274886] Starting BluetoothController Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "alarm-clock"... Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "metavolumio"... Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "airplay_emulation"... Jun 30 09:21:15 musico volumio[1094]: info: Starting Shairport Sync Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "cd_controller"... Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "last_100"... Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "streaming_services"... Jun 30 09:21:15 musico volumio[1094]: info: Loading plugin "webradio"... Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "i2s_dacs"... Jun 30 09:21:16 musico volumio[1094]: info: I2S DAC not set, start Auto-detection Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "volumiodiscovery"... Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 09:21:16 musico node[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 09:21:16 musico node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** For more information see Jun 30 09:21:16 musico node[1094]: *** WARNING *** For more information see Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 09:21:16 musico node[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 09:21:16 musico node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 09:21:16 musico node[1094]: *** WARNING *** For more information see Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 09:21:16 musico volumio[1094]: *** WARNING *** For more information see Jun 30 09:21:16 musico volumio[1094]: Discovery: StartAdv! undefined Jun 30 09:21:16 musico volumio[1094]: Discovery: Started advertising... Musico - undefined Jun 30 09:21:16 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "outputs"... Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "albumart"... Jun 30 09:21:16 musico volumio[1094]: info: Plugin example_plugin is not enabled Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "hi_res_audio"... Jun 30 09:21:16 musico firststart.sh[1095]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 30 09:21:16 musico firststart.sh[1095]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "inputs"... Jun 30 09:21:16 musico volumio[1094]: info: Loading plugin "qobuz"... Jun 30 09:21:16 musico systemd[1]: Reloading. Jun 30 09:21:16 musico systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 30 09:21:16 musico systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 30 09:21:16 musico systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 30 09:21:16 musico systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 30 09:21:16 musico volumio[1094]: Forking 3 albumart workers Jun 30 09:21:17 musico firststart.sh[1095]: Disabling firststart service Jun 30 09:21:17 musico firststart.sh[1095]: Removed symlink /etc/systemd/system/multi-user.target.wants/firststart.service. Jun 30 09:21:17 musico systemd[1]: Reloading. Jun 30 09:21:17 musico systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 30 09:21:17 musico systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 30 09:21:17 musico systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 30 09:21:17 musico systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 30 09:21:17 musico firststart.sh[1095]: Finalizing Jun 30 09:21:18 musico volumio[1094]: Starting albumart workers Jun 30 09:21:18 musico volumio[1094]: Starting albumart workers Jun 30 09:21:18 musico volumio[1094]: Starting albumart workers Jun 30 09:21:20 musico volumio[1094]: info: Loading plugin "tidal"... Jun 30 09:21:22 musico volumio[1094]: info: Loading plugin "volspotconnect2"... Jun 30 09:21:24 musico volumio-remote-updater[782]: [2025-06-30 09:21:24] [connect] Successful connection Jun 30 09:21:24 musico volumio[1094]: info: Loading plugin "updater_comm"... Jun 30 09:21:24 musico volumio[1094]: info: Plugin mpdemulation is not enabled Jun 30 09:21:24 musico volumio[1094]: info: Loading plugin "rest_api"... Jun 30 09:21:24 musico volumio[1094]: info: Loading plugin "websocket"... Jun 30 09:21:24 musico volumio[1094]: info: ___________ START PLUGINS ___________ Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 09:21:24 musico volumio[1094]: info: [1751275284867] CoreMusicLibrary::Adding element Media Servers Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 09:21:24 musico volumio[1094]: info: Adding METAVOLUMIO REST API Endpoints Jun 30 09:21:24 musico volumio[1094]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:24 musico sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Musico Jun 30 09:21:24 musico sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:24 musico sudo[1472]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:24 musico volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 09:21:24 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 09:21:25 musico volumio[1094]: info: Preparing CD Folders Jun 30 09:21:25 musico volumio[1094]: info: Adding CD REST API Endpoints Jun 30 09:21:25 musico volumio[1094]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jun 30 09:21:25 musico volumio[1094]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jun 30 09:21:25 musico volumio[1094]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jun 30 09:21:25 musico volumio[1094]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 30 09:21:25 musico volumio[1094]: info: Detecting CD presence Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 09:21:25 musico volumio[1094]: info: [1751275285044] CoreMusicLibrary::Adding element Last_100 Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:25 musico volumio[1094]: info: Streaming services startup Jun 30 09:21:25 musico volumio[1094]: info: Starting Streaming Daemon Jun 30 09:21:25 musico sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 30 09:21:25 musico sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 09:21:25 musico volumio[1094]: info: [1751275285099] CoreMusicLibrary::Adding element Webradio Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 09:21:25 musico systemd[1]: Stopping Volumio Streaming Daemon... Jun 30 09:21:25 musico systemd[1]: Starting Volumio Streaming Daemon... Jun 30 09:21:25 musico systemd[1]: Failed to reset devices.list on /system.slice: Invalid argument Jun 30 09:21:25 musico systemd[1]: Started Volumio Streaming Daemon. Jun 30 09:21:25 musico sudo[1487]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:25 musico volumio[1094]: error: Hi Res Audio Failed Login: Missing Login Data Jun 30 09:21:25 musico volumio[1094]: info: Scanning Audio Inputs Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 09:21:25 musico volumio[1094]: info: [1751275285130] CoreMusicLibrary::Adding element Analog In Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: ############################ Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: # Volumio Streaming Daemon # Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: # Running on port 7777 # Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: ############################ Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: Checking variant in static cache Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: Trying to get enabled services (HTTPS connection) for variant vivaldi Jun 30 09:21:25 musico sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jun 30 09:21:25 musico volumio[1094]: info: Loading i18n strings for locale en Jun 30 09:21:25 musico volumio[1094]: Updating browse sources language Jun 30 09:21:25 musico volumio[1094]: Cannot find translation for sourceMedia Servers Jun 30 09:21:25 musico volumio[1094]: Cannot find translation for sourceAnalog In Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:25 musico sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:25 musico volumio-streaming-daemon[1497]: Enabled services: Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::initPlayerControls Jun 30 09:21:25 musico systemd[1]: Starting Volspotconnect2 Daemon... Jun 30 09:21:25 musico systemd[1]: Started Volspotconnect2 Daemon. Jun 30 09:21:25 musico sudo[1521]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1534]: /bin/bash: /tmp/startconnect.sh: No such file or directory Jun 30 09:21:25 musico systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Jun 30 09:21:25 musico systemd[1]: Unit volspotconnect2.service entered failed state. Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 09:21:25 musico volumio[1094]: info: BOOT COMPLETED Jun 30 09:21:25 musico volumio[1094]: [Metrics] CommandRouter: 17s 126.49ms Jun 30 09:21:25 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 09:21:25 musico kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jun 30 09:21:25 musico kernel: arizona spi0.1: ASRC underclocked Jun 30 09:21:27 musico systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jun 30 09:21:27 musico systemd[1]: Stopping Volspotconnect2 Daemon... Jun 30 09:21:27 musico systemd[1]: Starting Volspotconnect2 Daemon... Jun 30 09:21:27 musico systemd[1]: Started Volspotconnect2 Daemon. Jun 30 09:21:27 musico volumio[1635]: /bin/bash: /tmp/startconnect.sh: No such file or directory Jun 30 09:21:27 musico systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Jun 30 09:21:27 musico systemd[1]: Unit volspotconnect2.service entered failed state. Jun 30 09:21:29 musico systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jun 30 09:21:29 musico systemd[1]: Stopping Volspotconnect2 Daemon... Jun 30 09:21:29 musico systemd[1]: Starting Volspotconnect2 Daemon... Jun 30 09:21:29 musico systemd[1]: Started Volspotconnect2 Daemon. Jun 30 09:21:29 musico volumio[1637]: /bin/bash: /tmp/startconnect.sh: No such file or directory Jun 30 09:21:29 musico systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Jun 30 09:21:29 musico systemd[1]: Unit volspotconnect2.service entered failed state. Jun 30 09:21:30 musico volumio[1094]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 30 09:21:30 musico volumio[1094]: info: CoreCommandRouter::Close All Modals sent Jun 30 09:21:30 musico volumio[1094]: info: CoreCommandRouter::Close All Modals sent Jun 30 09:21:30 musico volumio[1094]: Express server listening on port 3000 Jun 30 09:21:30 musico volumio[1094]: [Metrics] WebUI: 24s 916.31ms Jun 30 09:21:30 musico volumio[1094]: Volumio Calling Home Jun 30 09:21:30 musico volumio[1094]: info: CoreStateMachine::resetVolumioState Jun 30 09:21:30 musico volumio[1094]: info: CoreStateMachine::getcurrentVolume Jun 30 09:21:30 musico volumio[1094]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 09:21:30 musico volumio[1094]: info: Stopping AccessToken refresher cron for QOBUZ Jun 30 09:21:30 musico volumio[1094]: info: AccessToken refresher cron started for QOBUZ Jun 30 09:21:30 musico volumio[1094]: info: Stopping AccessToken refresher cron Jun 30 09:21:31 musico volumio[1094]: info: AccessToken refresher cron started Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 09:21:31 musico sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 09:21:31 musico sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:31 musico sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 09:21:31 musico sudo[1655]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:31 musico sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:31 musico sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 30 09:21:31 musico sudo[1657]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:31 musico sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:31 musico systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 30 09:21:31 musico systemd[1]: Starting Volumio Bluetooth Module... Jun 30 09:21:31 musico systemd[1]: Started Volumio Bluetooth Module. Jun 30 09:21:31 musico volumio[1094]: info: Cannot read play queue from file Jun 30 09:21:31 musico sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 30 09:21:31 musico sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:31 musico volumiobt[1678]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 30 09:21:31 musico sudo[1679]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:31 musico volumio[1094]: info: CoreStateMachine::pushState Jun 30 09:21:31 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::volumioPushState Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 09:21:31 musico volumio[1094]: info: CoreStateMachine::updateTrackBlock Jun 30 09:21:31 musico volumio[1094]: info: CorePlayQueue::getTrackBlock Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 09:21:31 musico sudo[1664]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:31 musico volumio[1094]: info: Setting Device type: Raspberry PI Jun 30 09:21:31 musico volumio[1094]: [SpotifyConnect] Vollibrespot Daemon Started Jun 30 09:21:31 musico volumio[1094]: [SpotifyConnect] Starting metadata listener Jun 30 09:21:31 musico volumio[1094]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Jun 30 09:21:31 musico volumio[1094]: Successfully set Record from Linein Jun 30 09:21:31 musico volumio[1094]: Successfully set Playback to Lineaout Jun 30 09:21:31 musico volumio[1094]: Successfully set Playback to SPDIF Jun 30 09:21:31 musico volumio[1094]: info: Bluetooth name changed to Musico Jun 30 09:21:31 musico volumio[1094]: info: MPD running with PID860 Jun 30 09:21:31 musico volumio[1094]: ,establishing connection Jun 30 09:21:31 musico sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jun 30 09:21:31 musico sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:31 musico sudo[1698]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:31 musico volumio[1094]: info: Volumio BT Module successfully started Jun 30 09:21:31 musico volumio[1094]: info: Volumio called home Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 09:21:31 musico volumio[1094]: info: CoreStateMachine::pushState Jun 30 09:21:31 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::volumioPushState Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:31 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 09:21:31 musico volumio[1094]: error: updateQueue error: null Jun 30 09:21:31 musico volumio[1094]: error: Failed LSINFO: Error: [50@0] {} No such directory Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 30 09:21:31 musico volumio[1094]: No protocol specified Jun 30 09:21:31 musico volumio[1094]: xcb_connection_has_error() returned true Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 30 09:21:31 musico volumio[1094]: info: Version has changed, forcing UI Reload Jun 30 09:21:31 musico volumio[1094]: info: CoreCommandRouter::Reload Ui Jun 30 09:21:31 musico volumio[1094]: Discovery: Changing my name to musico CINGHIALE is undefined Jun 30 09:21:31 musico systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jun 30 09:21:31 musico systemd[1]: Stopping Volspotconnect2 Daemon... Jun 30 09:21:31 musico systemd[1]: Starting Volspotconnect2 Daemon... Jun 30 09:21:31 musico systemd[1]: Started Volspotconnect2 Daemon. Jun 30 09:21:32 musico volumio[1094]: info: Starting Shairport Sync Jun 30 09:21:32 musico sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 09:21:32 musico volumio[1094]: info: Starting Shairport Sync Jun 30 09:21:32 musico sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:32 musico sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 30 09:21:32 musico systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 30 09:21:32 musico sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:32 musico volumio[1094]: Discovery: adding undefined Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device undefined Jun 30 09:21:32 musico systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 09:21:32 musico sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico sudo[1715]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:32 musico sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:32 musico systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 30 09:21:32 musico systemd[1]: Started UPnP Renderer front-end to MPD. Jun 30 09:21:32 musico sudo[1718]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:32 musico systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 30 09:21:32 musico systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 30 09:21:32 musico systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 09:21:32 musico sudo[1724]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:32 musico volumio[1711]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Jun 30 09:21:32 musico volumio[1094]: Discovery: this is already registered, undefined Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device undefined Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: Playing: false Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: VOLATILE: Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: STATE:undefined Jun 30 09:21:32 musico volumio[1094]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: Playing: false Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: VOLATILE: Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: STATE:undefined Jun 30 09:21:32 musico sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 30 09:21:32 musico sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:32 musico volumio[1094]: info: Upmpdcli Daemon Started Jun 30 09:21:32 musico volumio[1094]: info: Shairport-Sync Started Jun 30 09:21:32 musico volumio[1094]: Error adding Membership: Error: addMembership EINVAL Jun 30 09:21:32 musico volumio[1094]: Discovery: this is already registered, undefined Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device undefined Jun 30 09:21:32 musico volumiobt[1678]: pulseaudio: no process found Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico sudo[1756]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: info: Shairport-Sync Started Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: Discovery: this is already registered, undefined Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device undefined Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1711]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jun 30 09:21:32 musico volumio[1094]: Discovery: this is already registered, undefined Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device undefined Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico dbus[862]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Jun 30 09:21:32 musico systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 30 09:21:32 musico dbus[862]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 30 09:21:32 musico systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 30 09:21:32 musico rtkit-daemon[1770]: Successfully called chroot. Jun 30 09:21:32 musico rtkit-daemon[1770]: Successfully dropped privileges. Jun 30 09:21:32 musico rtkit-daemon[1770]: Successfully limited resources. Jun 30 09:21:32 musico rtkit-daemon[1770]: Running. Jun 30 09:21:32 musico rtkit-daemon[1770]: Canary thread running. Jun 30 09:21:32 musico rtkit-daemon[1770]: Watchdog thread running. Jun 30 09:21:32 musico volumio[1094]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: Playing: false Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: VOLATILE: Jun 30 09:21:32 musico volumio[1094]: BT PLUGIN MESSAGE: STATE:undefined Jun 30 09:21:32 musico volumio[1094]: Discovery: adding eca5395b-f276-477a-b99d-8d3ce2675615 Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device Musico Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: Discovery: this is already registered, eca5395b-f276-477a-b99d-8d3ce2675615 Jun 30 09:21:32 musico volumio[1094]: info: mDNS: Found device Musico Jun 30 09:21:32 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:32 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:32 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:33 musico volumio[1094]: info: mDNS: A device disapperared from network Jun 30 09:21:33 musico volumio[1094]: info: mDNS: Device musico disapperared from network Jun 30 09:21:33 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:33 musico volumio[1094]: info: mDNS: A device disapperared from network Jun 30 09:21:33 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:33 musico pulseaudio[1769]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 30 09:21:33 musico pulseaudio[1769]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 30 09:21:33 musico pulseaudio[1769]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jun 30 09:21:33 musico bluetoothd[1031]: Endpoint registered: sender=:1.26 path=/MediaEndpoint/A2DPSource Jun 30 09:21:33 musico bluetoothd[1031]: Endpoint registered: sender=:1.26 path=/MediaEndpoint/A2DPSink Jun 30 09:21:33 musico volumiobt[1678]: Applying permissions Jun 30 09:21:33 musico volumiobt[1678]: Setting BT discoverable and pairable Jun 30 09:21:33 musico volumiobt[1678]: [104B blob data] Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# -e power on Jun 30 09:21:33 musico volumiobt[1678]: Invalid command Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# agent on Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# discoverable on Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# pairable on Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# agent NoInputNoOutput Jun 30 09:21:33 musico volumiobt[1678]: Failed to register agent object Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# default-agent Jun 30 09:21:33 musico volumiobt[1678]: No agent is registered Jun 30 09:21:33 musico volumiobt[1678]: [bluetooth]# quit Jun 30 09:21:33 musico volumiobt[1678]: [61B blob data] Jun 30 09:21:33 musico volumiobt[1678]: [90B blob data] Jun 30 09:21:33 musico volumiobt[1678]: [62B blob data] Jun 30 09:21:33 musico volumio-streaming-daemon[1497]: Checking variant in static cache Jun 30 09:21:33 musico volumio-streaming-daemon[1497]: Trying to get enabled services (HTTPS connection) for variant vivaldi Jun 30 09:21:34 musico volumio[1094]: info: Fetching Streaming Services browse cache Jun 30 09:21:34 musico sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 09:21:34 musico sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 09:21:34 musico sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:34 musico sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:34 musico sudo[1793]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:34 musico sudo[1795]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:34 musico volumio[1711]: [Vollibrespot] : couldn't parse packet from V4(10.10.110.212:5353): type 47 is invalid Jun 30 09:21:34 musico sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 09:21:34 musico sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:34 musico sudo[1816]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:34 musico sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 09:21:34 musico sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:21:34 musico sudo[1818]: pam_unix(sudo:session): session closed for user root Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:35 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 09:21:35 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:21:35 musico volumio[1094]: info: Listing playlists Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 30 09:21:35 musico volumio[1094]: error: Could not retrieve plugin system_controller my_volumio Jun 30 09:21:35 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 30 09:21:37 musico volumio[1094]: Discovery: adding eca5395b-f276-477a-b99d-8d3ce2675615 Jun 30 09:21:37 musico volumio[1094]: info: mDNS: Found device musico Jun 30 09:21:37 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:37 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:37 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:37 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:37 musico volumio[1094]: Discovery: this is already registered, eca5395b-f276-477a-b99d-8d3ce2675615 Jun 30 09:21:37 musico volumio[1094]: info: mDNS: Found device musico Jun 30 09:21:37 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:37 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:37 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:37 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:39 musico volumio-remote-updater[782]: [2025-06-30 09:21:39] [connect] Successful connection Jun 30 09:21:39 musico volumio-remote-updater[782]: [2025-06-30 09:21:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1751275299 101 Jun 30 09:21:45 musico volumio[1094]: info: CoreCommandRouter::volumioGetState Jun 30 09:21:45 musico volumio[1094]: info: CorePlayQueue::getTrack 0 Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 09:21:46 musico volumio[1094]: error: Could not retrieve plugin audio_interface multiroom Jun 30 09:21:46 musico volumio[1094]: info: Listing playlists Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 30 09:21:46 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 30 09:21:46 musico volumio[1094]: error: Could not retrieve plugin system_controller my_volumio Jun 30 09:21:47 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 30 09:21:54 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 09:21:54 musico volumio[1094]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 30 09:21:54 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 30 09:21:54 musico volumio[1094]: error: Could not retrieve plugin system_controller my_volumio Jun 30 09:21:54 musico volumio[1094]: info: Received Get System Version Jun 30 09:21:54 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 09:21:55 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 09:21:55 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 09:22:01 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 09:22:01 musico volumio[1094]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 30 09:22:01 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 30 09:22:01 musico volumio[1094]: error: Could not retrieve plugin system_controller my_volumio Jun 30 09:22:01 musico volumio[1094]: info: Received Get System Version Jun 30 09:22:01 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 09:22:02 musico volumio[1711]: [Vollibrespot] : couldn't parse packet from V4(10.10.110.212:5353): type 47 is invalid Jun 30 09:22:15 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 30 09:22:15 musico volumio[1094]: info: Loading i18n strings for locale it Jun 30 09:22:16 musico volumio[1094]: Updating browse sources language Jun 30 09:22:16 musico volumio[1094]: Cannot find translation for sourceMedia Servers Jun 30 09:22:16 musico volumio[1094]: Cannot find translation for sourceAnalog In Jun 30 09:22:16 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 09:22:16 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 30 09:22:16 musico volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 30 09:22:16 musico sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 30 09:22:16 musico volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 09:22:16 musico volumio[1094]: TypeError: Cannot read property 'name' of null Jun 30 09:22:16 musico volumio[1094]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1462:42) Jun 30 09:22:16 musico volumio[1094]: at emitTwo (events.js:126:13) Jun 30 09:22:16 musico volumio[1094]: at Socket.emit (events.js:214:7) Jun 30 09:22:16 musico volumio[1094]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 30 09:22:16 musico volumio[1094]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 30 09:22:16 musico volumio[1094]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 30 09:22:16 musico sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 09:22:16 musico volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 09:22:17 musico sudo[1986]: pam_unix(sudo:session): session closed for user root Jun 30 09:22:17 musico sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 09:21 Jun 30 09:22:17 musico sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="vivaldi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 15:11:44 CEST 2021" VOLUMIO_VERSION="1.081" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Vivaldi" VOLUMIO_MODEL="Musico" VOLUMIO_VENDOR_MODEL="Vivaldi Musico" VOLUMIO_HASH="2294d51e0d5fb0a1d09a7619c5ec8086"