Aug 30 17:33:00 volumiogimbal volumio-remote-updater[977]: [2026-08-30 17:33:00] [connect] Successful connection Aug 30 17:33:00 volumiogimbal ntfs-3g[1836]: Version 2022.10.3 integrated FUSE 28 Aug 30 17:33:00 volumiogimbal ntfs-3g[1836]: Mounted /dev/sdb5 (Read-Write, label "muzssd_E", NTFS 3.1) Aug 30 17:33:00 volumiogimbal ntfs-3g[1836]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 30 17:33:00 volumiogimbal ntfs-3g[1836]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb5,blkdev,blksize=4096 Aug 30 17:33:00 volumiogimbal ntfs-3g[1836]: Global ownership and permissions enforced, configuration type 7 Aug 30 17:33:00 volumiogimbal sudo[1809]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:00 volumiogimbal volumio[1408]: sudo: unable to resolve host volumiogimbal: System error Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Ignoring mount for partition: boot Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Ignoring mount for partition: volumio Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Ignoring mount for partition: volumio_data Aug 30 17:33:00 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Loading plugin "volumio_command_line_client"... Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Loading plugin "upnp"... Aug 30 17:33:00 volumiogimbal volumio[1408]: info: [1788111180695] Starting Upmpd Daemon Aug 30 17:33:00 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Loading plugin "my_music"... Aug 30 17:33:00 volumiogimbal volumio[1408]: info: Loading plugin "mpd"... Aug 30 17:33:01 volumiogimbal volumio[1408]: info: Loading plugin "upnp_browser"... Aug 30 17:33:02 volumiogimbal volumio[1408]: info: Starting UPNP Browser Aug 30 17:33:02 volumiogimbal volumio[1408]: info: Loading plugin "alarm-clock"... Aug 30 17:33:04 volumiogimbal volumio[1408]: info: Loading plugin "airplay_emulation"... Aug 30 17:33:05 volumiogimbal systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Starting Shairport Sync Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "last_100"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "webradio"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "i2s_dacs"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: I2S DAC not set, start Auto-detection Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "volumiodiscovery"... Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** For more information see Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 17:33:05 volumiogimbal volumio[1408]: *** WARNING *** For more information see Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** For more information see Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 17:33:05 volumiogimbal node[1408]: *** WARNING *** For more information see Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Discovery: Started advertising with name: Volumio_gimbal Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "outputs"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "albumart"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Plugin example_plugin is not enabled Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "inputs"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "updater_comm"... Aug 30 17:33:05 volumiogimbal volumio[1862]: Forking 3 albumart workers Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Plugin mpdemulation is not enabled Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "rest_api"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading plugin "websocket"... Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Starting Socket.io Server version 1.7.4 Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Loading i18n strings for locale en Aug 30 17:33:05 volumiogimbal volumio[1408]: Updating browse sources language Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:05 volumiogimbal volumio[1872]: Starting albumart workers Aug 30 17:33:05 volumiogimbal volumio[1873]: Starting albumart workers Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1874]: Starting albumart workers Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::initPlayerControls Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:33:05 volumiogimbal volumio[1408]: Express server listening on port 3000 Aug 30 17:33:05 volumiogimbal volumio[1408]: [Metrics] WebUI: 13s 123.13ms Aug 30 17:33:05 volumiogimbal volumio[1408]: info: Setting Device type: x86 Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreStateMachine::resetVolumioState Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreStateMachine::getcurrentVolume Aug 30 17:33:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Completed loading Core Plugins Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Preparing to generate the ALSA configuration file Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Volumio Network Manager: Network status updated: 2 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 30 17:33:06 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 192.168.0.129 from 192.168.0.148 UA: Mozilla/5.0 (Linux; Android 12; Tab 16 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Reloading queue from file Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Asound.conf file unchanged, so no further update is needed Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Output device has changed, restarting MPD Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Output device has changed, restarting Shairport Sync Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 17:33:06 volumiogimbal sudo[1946]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal volumio[1408]: info: ___________ START PLUGINS ___________ Aug 30 17:33:06 volumiogimbal sudo[1946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 17:33:06 volumiogimbal sudo[1946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:06 volumiogimbal sudo[1944]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 17:33:06 volumiogimbal sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:06 volumiogimbal volumio[1408]: info: ControllerMpd::onStart: Initializing MPD Aug 30 17:33:06 volumiogimbal sudo[1944]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Creating MPD Configuration file Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 17:33:06 volumiogimbal volumio[1408]: info: [1788111186431] CoreMusicLibrary::Adding element Media Servers Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:06 volumiogimbal sudo[1960]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 30 17:33:06 volumiogimbal volumio[1408]: info: UPNP Browser: Client initialized successfully Aug 30 17:33:06 volumiogimbal sudo[1960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:06 volumiogimbal systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 17:33:06 volumiogimbal systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal sudo[1963]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1965]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 17:33:06 volumiogimbal sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:06 volumiogimbal sudo[1965]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:06 volumiogimbal sudo[1963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 17:33:06 volumiogimbal sudo[1963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:06 volumiogimbal systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal sudo[1960]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:06 volumiogimbal dhcpcd[926]: timed out Aug 30 17:33:06 volumiogimbal sh[912]: timed out Aug 30 17:33:06 volumiogimbal dhcpcd[926]: dhcpcd exited Aug 30 17:33:06 volumiogimbal sh[912]: dhcpcd exited Aug 30 17:33:06 volumiogimbal sh[847]: ifup: failed to bring up eth0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 17:33:06 volumiogimbal volumio[1408]: info: [1788111186514] CoreMusicLibrary::Adding element Last_100 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:06 volumiogimbal systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 30 17:33:06 volumiogimbal systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 30 17:33:06 volumiogimbal systemd[1]: mpd.service: Deactivated successfully. Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 17:33:06 volumiogimbal systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 17:33:06 volumiogimbal volumio[1408]: info: [1788111186519] CoreMusicLibrary::Adding element Webradio Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:33:06 volumiogimbal systemd[1]: mpd.socket: Deactivated successfully. Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Initializing BBC Radios Aug 30 17:33:06 volumiogimbal systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 17:33:06 volumiogimbal systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 17:33:06 volumiogimbal systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 17:33:06 volumiogimbal systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Volumio Calling Home Aug 30 17:33:06 volumiogimbal sudo[1987]: root : unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1987]: sudo: unable to resolve host volumiogimbal: System error Aug 30 17:33:06 volumiogimbal sudo[1987]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 17:33:06 volumiogimbal sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 17:33:06 volumiogimbal sudo[2002]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 17:33:06 volumiogimbal sudo[1987]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::setRepeat null single undefined Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::pushState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::setRandom null Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::pushState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 17:33:06 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 192.168.0.129 from 192.168.0.148 UA: Mozilla/5.0 (Linux; Android 12; Tab 16 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: VolumeController:: Volume=46 Mute =false Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::pushState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreStateMachine::updateTrackBlock Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrackBlock Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 17:33:06 volumiogimbal volumio[1408]: info: MPD Permissions set Aug 30 17:33:06 volumiogimbal volumio[1408]: info: MPD Permissions set Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Discovery: adding a84d9e18-39b8-487d-beac-89039b69e328 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Discovery: Found device Volumio_gimbal Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:06 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 30 17:33:06 volumiogimbal volumio[1408]: info: Volumio called home Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:06 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Discovery: this is already registered, a84d9e18-39b8-487d-beac-89039b69e328 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Discovery: Found device Volumio_gimbal Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Starting Shairport Sync Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Starting Shairport Sync Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Starting Shairport Sync Aug 30 17:33:07 volumiogimbal sudo[2043]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:07 volumiogimbal sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 17:33:07 volumiogimbal sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:07 volumiogimbal sudo[2047]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:07 volumiogimbal sudo[2045]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:07 volumiogimbal sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 17:33:07 volumiogimbal sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:07 volumiogimbal sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 17:33:07 volumiogimbal sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:07 volumiogimbal volumio[1408]: info: VolumeController:: Volume=46 Mute =false Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreStateMachine::pushState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 17:33:07 volumiogimbal systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 17:33:07 volumiogimbal systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 17:33:07 volumiogimbal systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 17:33:07 volumiogimbal systemd[1]: shairport-sync.service: Consumed 1.494s CPU time. Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 17:33:07 volumiogimbal sudo[2045]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:07 volumiogimbal sudo[2043]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:07 volumiogimbal sudo[2047]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Shairport-Sync Started Aug 30 17:33:07 volumiogimbal volumio[1408]: Error adding Membership: Error: addMembership EINVAL Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Shairport-Sync Started Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Shairport-Sync Started Aug 30 17:33:07 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:07.536Z level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 17:33:07 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 30 17:33:07 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:07 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:07.712Z level=INFO msg="system info for ecaac5d01721916bf2eb0f85bc2167fe" deviceName=Volumio_gimbal deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 30 17:33:07 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:07.772Z level=INFO msg="bootstrapping state" hasInternet=true Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:07 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 192.168.0.129 from 192.168.0.148 UA: Mozilla/5.0 (Linux; Android 12; Tab 16 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:07 volumiogimbal volumio[1408]: info: Listing playlists Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetQueue Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CoreStateMachine::getQueue Aug 30 17:33:07 volumiogimbal volumio[1408]: info: CorePlayQueue::getQueue Aug 30 17:33:08 volumiogimbal systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 30 17:33:08 volumiogimbal systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 30 17:33:08 volumiogimbal systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 17:33:08 volumiogimbal systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 17:33:08 volumiogimbal upmpdcli[2113]: Could not open config: /tmp/upmpdcli.conf Aug 30 17:33:08 volumiogimbal systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 17:33:08 volumiogimbal systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 17:33:09 volumiogimbal systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 30 17:33:10 volumiogimbal systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 30 17:33:10 volumiogimbal systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 30 17:33:10 volumiogimbal sudo[2205]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:10 volumiogimbal sudo[2208]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:10 volumiogimbal sudo[2205]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:33:10 volumiogimbal sudo[2205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:10 volumiogimbal sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:33:10 volumiogimbal sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:10 volumiogimbal sudo[2205]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:10 volumiogimbal sudo[2208]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:10 volumiogimbal sudo[2214]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:10 volumiogimbal sudo[2214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 17:33:10 volumiogimbal sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:11 volumiogimbal mpd[2003]: 2026-08-30T17:33:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 17:33:11 volumiogimbal systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 17:33:11 volumiogimbal sudo[1946]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:11 volumiogimbal sudo[1963]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:11 volumiogimbal volumio[1408]: info: Completed starting Core Plugins Aug 30 17:33:11 volumiogimbal volumio[1408]: info: ------------------------------------------- Aug 30 17:33:11 volumiogimbal volumio[1408]: info: ----- MyVolumio plugins startup ---- Aug 30 17:33:11 volumiogimbal volumio[1408]: info: ------------------------------------------- Aug 30 17:33:11 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 17:33:11 volumiogimbal volumio[1408]: error: MPD error: The expression evaluated to a falsy value: Aug 30 17:33:11 volumiogimbal volumio[1408]: assert.ok(self.idling) Aug 30 17:33:11 volumiogimbal volumio[1408]: error: The expression evaluated to a falsy value: Aug 30 17:33:11 volumiogimbal volumio[1408]: assert.ok(self.idling) Aug 30 17:33:11 volumiogimbal volumio[1408]: error: updateQueue error: null Aug 30 17:33:11 volumiogimbal volumio[1408]: info: MPD running with PID2003 Aug 30 17:33:11 volumiogimbal volumio[1408]: ,establishing connection Aug 30 17:33:11 volumiogimbal volumio[1408]: error: updateQueue error: null Aug 30 17:33:12 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 30 17:33:13 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetQueue Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreStateMachine::getQueue Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CorePlayQueue::getQueue Aug 30 17:33:14 volumiogimbal volumio[1408]: info: Listing playlists Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 17:33:14 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:14 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 17:33:14 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:15 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:15 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:15 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:15 volumiogimbal volumio-remote-updater[977]: [2026-08-30 17:33:15] [connect] Successful connection Aug 30 17:33:15 volumiogimbal volumio-remote-updater[977]: [2026-08-30 17:33:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788111195 101 Aug 30 17:33:15 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 8 Aug 30 17:33:16 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 17:33:23 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 17:33:23 volumiogimbal systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 30 17:33:23 volumiogimbal systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 17:33:24 volumiogimbal systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 17:33:24 volumiogimbal sudo[2214]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 17:33:24 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 17:33:25 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:25 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:25 volumiogimbal volumio[1408]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 17:33:25 volumiogimbal volumio[1408]: info: MyVolumio not started Aug 30 17:33:25 volumiogimbal volumio[1408]: info: Initializing device activation check Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 17:33:25 volumiogimbal volumio[1408]: info: Streaming services startup Aug 30 17:33:25 volumiogimbal volumio[1408]: info: Starting Streaming Daemon Aug 30 17:33:25 volumiogimbal volumio[1408]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 17:33:25 volumiogimbal sudo[2278]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:25 volumiogimbal sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 17:33:25 volumiogimbal sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:25 volumiogimbal sudo[2278]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:26 volumiogimbal volumio[1408]: info: Upmpdcli Daemon Started Aug 30 17:33:27 volumiogimbal volumio[1408]: error: Cannot start Volumio Streaming Daemon Aug 30 17:33:27 volumiogimbal volumio[1408]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 17:33:27 volumiogimbal volumio[1408]: sudo: unable to resolve host volumiogimbal: System error Aug 30 17:33:27 volumiogimbal volumio[1408]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 17:33:32 volumiogimbal volumio[1408]: info: Received Get System Version Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:33:32 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:32 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:32 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:36 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:33:36 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:36 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:33:36 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 30 17:33:36 volumiogimbal volumio-remote-updater[977]: Test mode disabled Aug 30 17:33:36 volumiogimbal volumio-remote-updater[977]: Alpha mode disabled Aug 30 17:33:36 volumiogimbal volumio-remote-updater[977]: Alpha legacy test mode disabled Aug 30 17:33:37 volumiogimbal volumio[1408]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:37 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:37.034Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 30 17:33:37 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:37.034Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 30 17:33:37 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:37.034Z level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:37 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:37 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 17:33:37 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 17:33:37 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:37.043Z level=INFO msg="enabling local network discovery" Aug 30 17:33:37 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:37.056Z level=INFO msg="enabling BLE discovery" Aug 30 17:33:38 volumiogimbal upmpdcli[2339]: writing RSA key Aug 30 17:33:38 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:38.427Z level=INFO msg="service successfully established" component=discovery/localnet Aug 30 17:33:40 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:33:40 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 30 17:33:40 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:40 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:33:45 volumiogimbal volumio[1408]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 30 17:33:45 volumiogimbal volumio[1408]: info: Completed starting MyVolumio Plugin Aug 30 17:33:45 volumiogimbal volumio[1408]: [Metrics] CommandRouter: 52s 128.60ms Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumiosetStartupVolume Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::Close All Modals sent Aug 30 17:33:45 volumiogimbal volumio[1408]: info: CoreCommandRouter::Close All Modals sent Aug 30 17:33:46 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 30 17:33:46 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 17:33:47 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 30 17:33:47 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:47.322Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.148:42262 Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.858Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-846.801056ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.858Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-849.155342ms timeout=20s Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.858Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 17:33:48 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:48 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.862Z level=INFO msg="emitting device name changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" name=Volumio_gimbal Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.972Z level=INFO msg="emitting device language changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" language=en Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.974Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" timezone=UTC Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.975Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.977Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" available=true connected=true macAddress=a4:c4:94:6c:35:3b ip4Address=192.168.0.129/24 ip6Address= ssid=CleverDove Aug 30 17:33:48 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:48.977Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" setupComplete=true Aug 30 17:33:48 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 17:33:49 volumiogimbal volumio[1408]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 Aug 30 17:33:49 volumiogimbal volumio[1408]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:33:49 volumiogimbal volumio[1408]: {"cmd":"/usr/local/bin/alsacap -C 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:33:49 volumiogimbal volumio[1408]: amixer -c 0 info | grep "Combo384 Amanero" Aug 30 17:33:49 volumiogimbal volumio[1408]: Card sysdefault:0 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-0000:00:15.0-8.1.3, high speed' Aug 30 17:33:49 volumiogimbal volumio[1408]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 Aug 30 17:33:49 volumiogimbal volumio[1408]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:33:49 volumiogimbal volumio[1408]: {"cmd":"/usr/local/bin/alsacap -C 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:33:49 volumiogimbal volumio[1408]: amixer -c 0 info | grep "Combo384 Amanero" Aug 30 17:33:49 volumiogimbal volumio[1408]: Card sysdefault:0 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-0000:00:15.0-8.1.3, high speed' Aug 30 17:33:49 volumiogimbal volumio[1408]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 30 17:33:49 volumiogimbal volumio[1408]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:33:49 volumiogimbal volumio[1408]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:33:49 volumiogimbal volumio[1408]: amixer -c 5 info | grep "XingCore USB Hi-Resolution Audi" Aug 30 17:33:49 volumiogimbal volumio[1408]: Card sysdefault:5 'Audi'/'XingCore XingCore USB Hi-Resolution Audi at usb-0000:00:15.0-8.2, high speed' Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.364Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" selectedOutputId=0 Aug 30 17:33:49 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:49 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.420Z level=INFO msg="emitting software info changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" currentVersion=4.119 latestVersion=4.119 Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.420Z level=INFO msg="emitting software update progress event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" status=UPDATE_STATUS_NONE progress=0 Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.420Z level=INFO msg="emitting user changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" userId= Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.420Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" providers=3 Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.422Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" plugins=0 Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:49 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.425Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" state=STATUS_STOPPED positionMs=0 volume=46 Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.425Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.148:42262 @ 0xc0000fd8f0" id= title= Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.566Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-859.74871ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.678Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s Aug 30 17:33:49 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:49.978Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=http://pushupdates.volumio.org duration=296.053769ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.083Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=404.442161ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.127Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=446.613323ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.146Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://www.googleapis.com duration=467.906123ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.167Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=487.975946ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.195Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://securetoken.googleapis.com duration=517.229801ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.221Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://database.volumio.cloud duration=540.284703ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.226Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://functions.volumio.cloud duration=547.626385ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.237Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://google.com duration=556.672879ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.281Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=602.566636ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.367Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=https://functions.volumio.cloud duration=688.207912ms Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.389Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=http://cddb.volumio.org duration=707.967845ms Aug 30 17:33:50 volumiogimbal sudo[2423]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:50 volumiogimbal sudo[2425]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:50 volumiogimbal sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:33:50 volumiogimbal sudo[2423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:50 volumiogimbal sudo[2425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:33:50 volumiogimbal sudo[2423]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:50 volumiogimbal sudo[2425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:50 volumiogimbal sudo[2425]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:50 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 192.168.0.129 from 192.168.0.148 UA: Mozilla/5.0 (Linux; Android 12; Tab 16 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 17:33:50 volumiogimbal volumio5-onboarding[1974]: time=2026-08-30T17:33:50.704Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.148:42262 @ 0xc0000fd8f0" latency=-845.622966ms timeout=10s endpoint=http://plugins.volumio.org duration=1.024873749s Aug 30 17:33:51 volumiogimbal sudo[2430]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:51 volumiogimbal sudo[2432]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:33:51 volumiogimbal sudo[2430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:33:51 volumiogimbal sudo[2430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:51 volumiogimbal sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:33:51 volumiogimbal sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:33:51 volumiogimbal sudo[2430]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:51 volumiogimbal sudo[2432]: pam_unix(sudo:session): session closed for user root Aug 30 17:33:51 volumiogimbal volumio[1408]: verbose: New Socket.io Connection to 192.168.0.129 from 192.168.0.148 UA: Mozilla/5.0 (Linux; Android 12; Tab 16 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 17:33:51 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:51 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:51 volumiogimbal volumio[1408]: info: Listing playlists Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:33:51 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 17:33:52 volumiogimbal volumio[1408]: info: BOOT COMPLETED Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:33:53 volumiogimbal volumio[1408]: info: Received Get System Info Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:33:53 volumiogimbal volumio[1408]: info: Discovery: Getting this device information Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CorePlayQueue::getTrack 0 Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:33:53 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 17:33:54 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioGetQueue Aug 30 17:33:54 volumiogimbal volumio[1408]: info: CoreStateMachine::getQueue Aug 30 17:33:54 volumiogimbal volumio[1408]: info: CorePlayQueue::getQueue Aug 30 17:34:01 volumiogimbal volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 17:34:05 volumiogimbal volumio[1408]: info: Preload queue cleared Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreStateMachine::ClearQueue Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreStateMachine::stop Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CorePlayQueue::clearPlayQueue Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CorePlayQueue::saveQueue Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CoreStateMachine::addQueueItems Aug 30 17:34:05 volumiogimbal volumio[1408]: info: CorePlayQueue::addQueueItems Aug 30 17:34:05 volumiogimbal volumio[1408]: info: Preload queue cleared Aug 30 17:34:05 volumiogimbal volumio[1408]: info: Adding Item to queue: music-library/USB/muzssd_E/ENG/Nazareth/Nazareth (1976) - Close Enough For Rock'n'Roll Aug 30 17:34:05 volumiogimbal volumio[1408]: info: Exploding uri music-library/USB/muzssd_E/ENG/Nazareth/Nazareth (1976) - Close Enough For Rock'n'Roll in service mpd Aug 30 17:34:05 volumiogimbal volumio[1408]: info: ALBUMART /albumart?cacheid=282&web=Nazareth/Close%20Enough%20For%20Rock'n'Roll/extralarge&path=%2Fmnt%2FUSB%2Fmuzssd_E%2FENG%2FNazareth%2FNazareth%20(1976)%20-%20Close%20Enough%20For%20Rock'n'Roll%2FNazareth%20-%20Close%20Enough%20For%20Rock'n'Roll.cue&metadata=false Aug 30 17:34:05 volumiogimbal volumio[1408]: info: URI /mnt/USB/muzssd_E/ENG/Nazareth/Nazareth (1976) - Close Enough For Rock'n'Roll/Nazareth - Close Enough For Rock'n'Roll.cue Aug 30 17:34:05 volumiogimbal volumio[1408]: info: ALBUMART /albumart?cacheid=282&web=Nazareth/Close%20Enough%20For%20Rock'n'Roll/extralarge&path=%2Fmnt%2FUSB%2Fmuzssd_E%2FENG%2FNazareth%2FNazareth%20(1976)%20-%20Close%20Enough%20For%20Rock'n'Roll%2FNazareth%20-%20Close%20Enough%20For%20Rock'n'Roll.cue&metadata=false Aug 30 17:34:05 volumiogimbal volumio[1408]: info: URI /mnt/USB/muzssd_E/ENG/Nazareth/Nazareth (1976) - Close Enough For Rock'n'Roll/Nazareth - Close Enough For Rock'n'Roll.cue Aug 30 17:34:05 volumiogimbal volumio[1408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:34:05 volumiogimbal volumio[1408]: Error: Unable to resolve or reject the same promise twice Aug 30 17:34:05 volumiogimbal volumio[1408]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 30 17:34:05 volumiogimbal volumio[1408]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 30 17:34:05 volumiogimbal volumio[1408]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 30 17:34:05 volumiogimbal volumio[1408]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 30 17:34:05 volumiogimbal volumio[1408]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 30 17:34:05 volumiogimbal volumio[1408]: at Socket.emit (node:events:514:28) Aug 30 17:34:05 volumiogimbal volumio[1408]: at addChunk (node:internal/streams/readable:343:12) Aug 30 17:34:05 volumiogimbal volumio[1408]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 30 17:34:05 volumiogimbal volumio[1408]: at Readable.push (node:internal/streams/readable:253:10) Aug 30 17:34:05 volumiogimbal volumio[1408]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 30 17:34:05 volumiogimbal volumio[1408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:34:05 volumiogimbal sudo[2480]: volumio : unable to resolve host volumiogimbal: System error Aug 30 17:34:05 volumiogimbal sudo[2480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 17:33' Aug 30 17:34:05 volumiogimbal sudo[2480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"