Oct 02 09:37:05 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 7. Oct 02 09:37:05 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:05 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:05 anneks run_daemon.sh[1715]: Traceback (most recent call last): Oct 02 09:37:05 anneks run_daemon.sh[1715]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:37:05 anneks run_daemon.sh[1715]: main() Oct 02 09:37:05 anneks run_daemon.sh[1715]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:37:05 anneks run_daemon.sh[1715]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:37:05 anneks run_daemon.sh[1715]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:37:05 anneks run_daemon.sh[1715]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:37:05 anneks run_daemon.sh[1715]: debug("Connecting to broker " + self.broker) Oct 02 09:37:05 anneks run_daemon.sh[1715]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:37:05 anneks run_daemon.sh[1715]: logfile = open(LOGFILE, "a") Oct 02 09:37:05 anneks run_daemon.sh[1715]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:37:05 anneks run_daemon.sh[1715]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:37:05 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:05 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 09:37:08 anneks volumio[1235]: error: MyVolumio Plugin failed to start in a timely fashion Oct 02 09:37:08 anneks volumio[1235]: [Metrics] CommandRouter: 77s 972.46ms Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::Close All Modals sent Oct 02 09:37:08 anneks volumio[1235]: info: CoreCommandRouter::Close All Modals sent Oct 02 09:37:10 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 09:37:10 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 09:37:10 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 09:37:11 anneks volumio5-onboarding[1548]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 02 09:37:11 anneks systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:11 anneks systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 02 09:37:11 anneks systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 02 09:37:11 anneks systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 09:37:11 anneks systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 09:37:11 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:11.656+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 02 09:37:15 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:15 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:16 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 8. Oct 02 09:37:16 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:16 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:16 anneks volumio[1235]: 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: 16 Oct 02 09:37:16 anneks volumio[1235]: info: BOOT COMPLETED Oct 02 09:37:16 anneks run_daemon.sh[1748]: Traceback (most recent call last): Oct 02 09:37:16 anneks run_daemon.sh[1748]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:37:16 anneks run_daemon.sh[1748]: main() Oct 02 09:37:16 anneks run_daemon.sh[1748]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:37:16 anneks run_daemon.sh[1748]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:37:16 anneks run_daemon.sh[1748]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:37:16 anneks run_daemon.sh[1748]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:37:16 anneks run_daemon.sh[1748]: debug("Connecting to broker " + self.broker) Oct 02 09:37:16 anneks run_daemon.sh[1748]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:37:16 anneks run_daemon.sh[1748]: logfile = open(LOGFILE, "a") Oct 02 09:37:16 anneks run_daemon.sh[1748]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:37:16 anneks run_daemon.sh[1748]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:37:16 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:16 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 02 09:37:17 anneks volumio[1235]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 09:37:17 anneks volumio[1235]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 09:37:17 anneks volumio[1235]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 09:37:17 anneks volumio[1235]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 09:37:18 anneks volumio[1235]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 02 09:37:18 anneks volumio[1235]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 09:37:18 anneks volumio[1235]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 09:37:18 anneks volumio[1235]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 02 09:37:27 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 9. Oct 02 09:37:27 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:27 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:27 anneks run_daemon.sh[1766]: Traceback (most recent call last): Oct 02 09:37:27 anneks run_daemon.sh[1766]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:37:27 anneks run_daemon.sh[1766]: main() Oct 02 09:37:27 anneks run_daemon.sh[1766]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:37:27 anneks run_daemon.sh[1766]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:37:27 anneks run_daemon.sh[1766]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:37:27 anneks run_daemon.sh[1766]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:37:27 anneks run_daemon.sh[1766]: debug("Connecting to broker " + self.broker) Oct 02 09:37:27 anneks run_daemon.sh[1766]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:37:27 anneks run_daemon.sh[1766]: logfile = open(LOGFILE, "a") Oct 02 09:37:27 anneks run_daemon.sh[1766]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:37:27 anneks run_daemon.sh[1766]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:37:27 anneks volumio[1235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 02 09:37:27 anneks volumio[1235]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 02 09:37:27 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:27 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:27 anneks volumio[1235]: info: Starting MyVolumio Remote Streaming Endpoints Oct 02 09:37:27 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:27 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:37:27 anneks volumio[1235]: info: MyVolumio login type: Token Oct 02 09:37:27 anneks volumio[1235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 09:37:27 anneks volumio[1235]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 09:37:28 anneks upmpdcli[1773]: writing RSA key Oct 02 09:37:34 anneks volumio[1235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 09:37:34 anneks volumio[1235]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 09:37:34 anneks volumio[1235]: info: Streaming services startup Oct 02 09:37:34 anneks volumio[1235]: info: Starting Streaming Daemon Oct 02 09:37:34 anneks volumio[1235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 09:37:34 anneks sudo[1788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 09:37:34 anneks sudo[1788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:37:35 anneks sudo[1788]: pam_unix(sudo:session): session closed for user root Oct 02 09:37:35 anneks volumio[1235]: 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: 16 Oct 02 09:37:35 anneks volumio[1235]: info: Received Get System Info Oct 02 09:37:35 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:37:35 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:37:35 anneks volumio[1235]: info: Discovery: Getting this device information Oct 02 09:37:35 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:35 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:35 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:37:35 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:35.437+02:00 level=INFO msg="system info for bf49b46d7a23f69cdf5a96fd82958d00" deviceName=Anneks deviceVariant="" deviceModel= softwareVersion="" Oct 02 09:37:35 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:35.454+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 02 09:37:38 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 10. Oct 02 09:37:38 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:38 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:39 anneks run_daemon.sh[1795]: Traceback (most recent call last): Oct 02 09:37:39 anneks run_daemon.sh[1795]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:37:39 anneks run_daemon.sh[1795]: main() Oct 02 09:37:39 anneks run_daemon.sh[1795]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:37:39 anneks run_daemon.sh[1795]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:37:39 anneks run_daemon.sh[1795]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:37:39 anneks run_daemon.sh[1795]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:37:39 anneks run_daemon.sh[1795]: debug("Connecting to broker " + self.broker) Oct 02 09:37:39 anneks run_daemon.sh[1795]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:37:39 anneks run_daemon.sh[1795]: logfile = open(LOGFILE, "a") Oct 02 09:37:39 anneks run_daemon.sh[1795]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:37:39 anneks run_daemon.sh[1795]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:37:39 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:39 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:37:44 anneks volumio[1235]: info: Received Get System Info Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:37:44 anneks volumio[1235]: info: Discovery: Getting this device information Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:44 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:37:44 anneks volumio[1235]: info: Preload queue cleared Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 09:37:44 anneks volumio[1235]: info: CoreStateMachine::ClearQueue Oct 02 09:37:44 anneks volumio[1235]: info: CoreStateMachine::stop Oct 02 09:37:44 anneks volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:37:44 anneks volumio[1235]: info: CorePlayQueue::clearPlayQueue Oct 02 09:37:44 anneks volumio[1235]: info: CorePlayQueue::saveQueue Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::volumioPushQueue Oct 02 09:37:44 anneks volumio[1235]: info: CoreStateMachine::addQueueItems Oct 02 09:37:44 anneks volumio[1235]: info: CorePlayQueue::addQueueItems Oct 02 09:37:44 anneks volumio[1235]: info: Preload queue cleared Oct 02 09:37:44 anneks volumio[1235]: info: Adding Item to queue: music-library/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128) Oct 02 09:37:44 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128) in service mpd Oct 02 09:37:44 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 09:37:44 anneks volumio[1235]: info: CURURI: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1) Oct 02 09:37:44 anneks volumio[1235]: info: Listing playlists Oct 02 09:37:44 anneks volumio[1235]: info: Listing playlists Oct 02 09:37:45 anneks volumio[1235]: error: Cannot start Volumio Streaming Daemon Oct 02 09:37:45 anneks volumio[1235]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 09:37:45 anneks volumio[1235]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 09:37:45 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2001%20-%20Have%20Fun.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2002%20-%20Loaded.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 02 - Loaded.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2003%20-%20Lighthouse%20Girl.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 03 - Lighthouse Girl.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2004%20-%20Sister%20Confusion.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 04 - Sister Confusion.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2005%20-%20The%20Wait.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 05 - The Wait.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2006%20-%20Step%20Inside.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 06 - Step Inside.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2007%20-%20We%20All%20Float%20Down%20Here%20Too!.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 07 - We All Float Down Here Too!.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/8%20soothing%20songs%20for%20Rut/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1992%208%20soothing%20songs%20for%20Rut%20(128)%2FMotorpsycho%20-%2008%20-%20California%20Dreamin'.mp3&metadata=false Oct 02 09:37:45 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 08 - California Dreamin'.mp3 Oct 02 09:37:45 anneks volumio[1235]: info: CoreCommandRouter::volumioPushQueue Oct 02 09:37:45 anneks volumio[1235]: info: CorePlayQueue::saveQueue Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::updateTrackBlock Oct 02 09:37:45 anneks volumio[1235]: info: CorePlayQueue::getTrackBlock Oct 02 09:37:45 anneks volumio[1235]: info: CoreCommandRouter::volumioPlay Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::play index 0 Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::stop Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::play index undefined Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:37:45 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:45 anneks volumio[1235]: info: CoreStateMachine::startPlaybackTimer Oct 02 09:37:45 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:45 anneks volumio[1235]: verbose: ControllerMpd::clearAddPlayTracks NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3 Oct 02 09:37:45 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 09:37:45 anneks volumio-remote-updater[637]: Test mode disabled Oct 02 09:37:45 anneks volumio-remote-updater[637]: Alpha mode disabled Oct 02 09:37:45 anneks volumio-remote-updater[637]: Alpha legacy test mode disabled Oct 02 09:37:45 anneks volumio[1235]: info: Preload queue cleared Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 01 - Psychonaut.flac Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 02 - Ozone.flac Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 03 - The ocean in her eye.flac Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 04 - Vortex surfer.flac Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 05 - Siddhardtino.flac Oct 02 09:37:45 anneks volumio[1235]: info: Preloading song: music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 06 - 577.flac Oct 02 09:37:45 anneks volumio[1235]: info: sendMpdCommand stop took 391 milliseconds Oct 02 09:37:45 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 09:37:46 anneks volumio[1235]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 09:37:46 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 05 - Siddhardtino.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 01 - Psychonaut.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 02 - Ozone.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 03 - The ocean in her eye.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 04 - Vortex surfer.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Exploding uri music-library/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 06 - 577.flac in service mpd Oct 02 09:37:46 anneks volumio[1235]: info: Oct 02 09:37:46 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:46 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2005%20-%20%20Siddhardtino.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 05 - Siddhardtino.flac Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2001%20-%20%20Psychonaut.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 01 - Psychonaut.flac Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2002%20-%20%20Ozone.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 02 - Ozone.flac Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2003%20-%20%20The%20ocean%20in%20her%20eye.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 03 - The ocean in her eye.flac Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2004%20-%20%20Vortex%20surfer.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 04 - Vortex surfer.flac Oct 02 09:37:46 anneks volumio[1235]: info: ALBUMART /albumart?cacheid=391&web=Motorpsycho/Trust%20Us/extralarge&path=%2Fmnt%2FNAS%2FElrond%2Fmusic%2FM%2FMotorpsycho%2F1998%20Trust%20Us%20(Disc%201)%2FMotorpsycho%20-%2006%20-%20%20577.flac&metadata=false Oct 02 09:37:46 anneks volumio[1235]: info: URI /mnt/NAS/Elrond/music/M/Motorpsycho/1998 Trust Us (Disc 1)/Motorpsycho - 06 - 577.flac Oct 02 09:37:46 anneks volumio[1235]: info: sendMpdCommand clear took 489 milliseconds Oct 02 09:37:46 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand add "NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3" Oct 02 09:37:46 anneks volumio[1235]: info: Oct 02 09:37:46 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:46 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:46 anneks volumio[1235]: info: Oct 02 09:37:46 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:46 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:46 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:37:46 anneks volumio[1235]: error: updateQueue error: null Oct 02 09:37:46 anneks volumio[1235]: info: Oct 02 09:37:46 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:46 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:46 anneks volumio[1235]: info: ------------------------------ 454ms Oct 02 09:37:46 anneks volumio[1235]: info: sendMpdCommand add "NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3" took 396 milliseconds Oct 02 09:37:46 anneks volumio[1235]: info: ------------------------------ 191ms Oct 02 09:37:46 anneks volumio[1235]: info: ------------------------------ 140ms Oct 02 09:37:46 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand play Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:47 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces system playlist update Oct 02 09:37:47 anneks volumio[1235]: info: Ignoring MPD Status Update Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:37:47 anneks volumio[1235]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces state update: player Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces state update: player Oct 02 09:37:47 anneks volumio[1235]: info: ------------------------------ 520ms Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand play took 467 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::getState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:37:47 anneks volumio[1235]: info: ------------------------------ 227ms Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::getState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:37:47 anneks volumio[1235]: info: ------------------------------ 226ms Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces state update: player Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::getState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:37:47 anneks volumio[1235]: info: Oct 02 09:37:47 anneks volumio[1235]: ---------------------------- MPD announces state update: player Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::getState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand status took 132 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand status took 124 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand status took 113 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand status took 82 milliseconds Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseState Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:37:47 anneks volumio[1235]: info: Received Get System Info Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:37:47 anneks volumio[1235]: info: Discovery: Getting this device information Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand playlistinfo took 212 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand playlistinfo took 212 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 02 09:37:47 anneks volumio[1235]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:37:47 anneks volumio[1235]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::servicePushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1169,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Have Fun","artist":"Motorpsycho","album":"8 soothing songs for Rut","uri":"NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3","trackType":"mp3"} Oct 02 09:37:47 anneks volumio[1235]: verbose: CURRENT POSITION 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState stateService play Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState currentStatus stop Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::servicePushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1169,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Have Fun","artist":"Motorpsycho","album":"8 soothing songs for Rut","uri":"NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3","trackType":"mp3"} Oct 02 09:37:47 anneks volumio[1235]: verbose: CURRENT POSITION 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState stateService play Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState currentStatus play Oct 02 09:37:47 anneks volumio[1235]: info: Received an update from plugin. extracting info from payload Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::servicePushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1169,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Have Fun","artist":"Motorpsycho","album":"8 soothing songs for Rut","uri":"NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3","trackType":"mp3"} Oct 02 09:37:47 anneks volumio[1235]: verbose: CURRENT POSITION 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState stateService play Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::syncState currentStatus play Oct 02 09:37:47 anneks volumio[1235]: info: Received an update from plugin. extracting info from payload Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:47 anneks volumio[1235]: info: ControllerMpd::pushState Oct 02 09:37:47 anneks volumio[1235]: info: CoreCommandRouter::servicePushState Oct 02 09:37:47 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:48 anneks volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1260,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Have Fun","artist":"Motorpsycho","album":"8 soothing songs for Rut","uri":"NAS/Elrond/music/M/Motorpsycho/1992 8 soothing songs for Rut (128)/Motorpsycho - 01 - Have Fun.mp3","trackType":"mp3"} Oct 02 09:37:48 anneks volumio[1235]: verbose: CURRENT POSITION 0 Oct 02 09:37:48 anneks volumio[1235]: info: CoreStateMachine::syncState stateService play Oct 02 09:37:48 anneks volumio[1235]: info: CoreStateMachine::syncState currentStatus play Oct 02 09:37:48 anneks volumio[1235]: info: Received an update from plugin. extracting info from payload Oct 02 09:37:48 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:48 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:48 anneks volumio[1235]: info: CoreStateMachine::pushState Oct 02 09:37:48 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::volumioPushState Oct 02 09:37:48 anneks volumio[1235]: info: ------------------------------ 681ms Oct 02 09:37:48 anneks volumio[1235]: info: ------------------------------ 961ms Oct 02 09:37:48 anneks volumio[1235]: info: ------------------------------ 941ms Oct 02 09:37:48 anneks volumio[1235]: info: ------------------------------ 911ms Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 09:37:48 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:48.445+02:00 level=INFO msg="enabling local network discovery" Oct 02 09:37:48 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:48.482+02:00 level=INFO msg="enabling BLE discovery" Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 09:37:48 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:37:48 anneks volumio[1235]: info: [LastFM] Current track has sufficient metadata: title (Have Fun) and artist (Motorpsycho) passed on explicitly Oct 02 09:37:49 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 11. Oct 02 09:37:49 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:49 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:37:49 anneks volumio5-onboarding[1738]: time=2026-10-02T09:37:49.438+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 09:37:49 anneks volumio[1235]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 09:37:50 anneks run_daemon.sh[1821]: Traceback (most recent call last): Oct 02 09:37:50 anneks run_daemon.sh[1821]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:37:50 anneks run_daemon.sh[1821]: main() Oct 02 09:37:50 anneks run_daemon.sh[1821]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:37:50 anneks run_daemon.sh[1821]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:37:50 anneks run_daemon.sh[1821]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:37:50 anneks run_daemon.sh[1821]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:37:50 anneks run_daemon.sh[1821]: debug("Connecting to broker " + self.broker) Oct 02 09:37:50 anneks run_daemon.sh[1821]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:37:50 anneks run_daemon.sh[1821]: logfile = open(LOGFILE, "a") Oct 02 09:37:50 anneks run_daemon.sh[1821]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:37:50 anneks run_daemon.sh[1821]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:37:50 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:37:50 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:37:50 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:50 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:51 anneks volumio[1235]: info: Listing playlists Oct 02 09:37:51 anneks volumio[1235]: info: Listing playlists Oct 02 09:37:51 anneks volumio[1235]: info: CoreCommandRouter::volumioGetState Oct 02 09:37:51 anneks volumio[1235]: info: CorePlayQueue::getTrack 0 Oct 02 09:37:51 anneks volumio[1235]: info: MyVolumio token set successfully Oct 02 09:37:51 anneks volumio[1235]: info: MYVOLUMIO: Adding device Oct 02 09:37:51 anneks volumio[1235]: info: MYVOLUMIO: Evaluating Server Oct 02 09:37:53 anneks volumio[1235]: info: MyVolumio Plan changed: premium Oct 02 09:37:53 anneks volumio[1235]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 02 09:37:53 anneks volumio[1235]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 09:37:53 anneks volumio[1235]: info: Removing audio output: Oct 02 09:37:53 anneks volumio[1235]: info: MYVOLUMIO: Adding device Oct 02 09:37:53 anneks volumio[1235]: info: MYVOLUMIO: Evaluating Server Oct 02 09:37:54 anneks volumio[1235]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: Remote config written successfully Oct 02 09:37:54 anneks volumio[1235]: info: Starting Tunnel 1 Oct 02 09:37:54 anneks volumio[1235]: info: Starting Tunnel Connection Checker Oct 02 09:37:54 anneks volumio[1235]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Have Fun","mbid":"3ccf0a62-3ad1-380f-bc18-73074a83f6ee","url":"https://www.last.fm/music/Motorpsycho/_/Have+Fun","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"2159","playcount":"6006","artist":{"name":"Motorpsycho","mbid":"5d0764c8-b14a-47fb-adc7-2ba9fb5f0d76","url":"https://www.last.fm/music/Motorpsycho"},"album":{"artist":"Motorpsycho","title":"8 Soothing Songs for Rut","url":"https://www.last.fm/music/Motorpsycho/8+Soothing+Songs+for+Rut","image":[{"#":"https://lastfm-img.freetls.fastly.net/i/u/34s/391826b020e342e0c2e481394e652a1b.png","@":{"size":"small"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/64s/391826b020e342e0c2e481394e652a1b.png","@":{"size":"medium"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/174s/391826b020e342e0c2e481394e652a1b.png","@":{"size":"large"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/300x300/391826b020e342e0c2e481394e652a1b.png","@":{"size":"extralarge"}}]},"userplaycount":"8","userloved":"0","toptags":""}} Oct 02 09:37:54 anneks volumio[1235]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:37:54 anneks volumio[1235]: info: MYVolumio Device enabled Oct 02 09:37:54 anneks volumio[1235]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 02 09:37:54 anneks volumio[1235]: [2026-10-02T07:37:54.598Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Oct 02 09:37:54 anneks volumio[1235]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/v-conf/index.js:166:13 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/multimap/index.js:140:9 Oct 02 09:37:54 anneks volumio[1235]: at Array.forEach () Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/multimap/index.js:139:13 Oct 02 09:37:54 anneks volumio[1235]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Oct 02 09:37:54 anneks volumio[1235]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Oct 02 09:37:54 anneks volumio[1235]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Oct 02 09:37:54 anneks volumio[1235]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Oct 02 09:37:54 anneks volumio[1235]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Oct 02 09:37:54 anneks volumio[1235]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Oct 02 09:37:54 anneks volumio[1235]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Oct 02 09:37:54 anneks volumio[1235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:37:54 anneks volumio[1235]: TypeError: Cannot read properties of null (reading 'replace') Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Oct 02 09:37:54 anneks volumio[1235]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Oct 02 09:37:54 anneks volumio[1235]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/v-conf/index.js:166:13 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/multimap/index.js:140:9 Oct 02 09:37:54 anneks volumio[1235]: at Array.forEach () Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/multimap/index.js:139:13 Oct 02 09:37:54 anneks volumio[1235]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Oct 02 09:37:54 anneks volumio[1235]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Oct 02 09:37:54 anneks volumio[1235]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Oct 02 09:37:54 anneks volumio[1235]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Oct 02 09:37:54 anneks volumio[1235]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Oct 02 09:37:54 anneks volumio[1235]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Oct 02 09:37:54 anneks volumio[1235]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Oct 02 09:37:54 anneks volumio[1235]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Oct 02 09:37:54 anneks volumio[1235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:38:00 anneks systemd[1]: ble-sensor-receive-daemon.service: Scheduled restart job, restart counter is at 12. Oct 02 09:38:00 anneks systemd[1]: Stopped ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:38:00 anneks systemd[1]: Started ble-sensor-receive-daemon.service - BLE Sensor Listening Daemon (Mitemp and TB Sense). Oct 02 09:38:00 anneks run_daemon.sh[1908]: Traceback (most recent call last): Oct 02 09:38:00 anneks run_daemon.sh[1908]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 517, in Oct 02 09:38:00 anneks run_daemon.sh[1908]: main() Oct 02 09:38:00 anneks run_daemon.sh[1908]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 486, in main Oct 02 09:38:00 anneks run_daemon.sh[1908]: mqtt_client = Mqtt(cfg['mqtt'], sensors) Oct 02 09:38:00 anneks run_daemon.sh[1908]: ^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 02 09:38:00 anneks run_daemon.sh[1908]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 109, in __init__ Oct 02 09:38:00 anneks run_daemon.sh[1908]: debug("Connecting to broker " + self.broker) Oct 02 09:38:01 anneks run_daemon.sh[1908]: File "/opt/ble-sensor-receive-daemon/ble-sensor-receive-daemon.py", line 64, in debug Oct 02 09:38:01 anneks run_daemon.sh[1908]: logfile = open(LOGFILE, "a") Oct 02 09:38:01 anneks run_daemon.sh[1908]: ^^^^^^^^^^^^^^^^^^ Oct 02 09:38:01 anneks run_daemon.sh[1908]: FileNotFoundError: [Errno 2] No such file or directory: '/var/log/ble-sensor-receive-daemon/ble-sensor-receive-daemon.log' Oct 02 09:38:01 anneks systemd[1]: ble-sensor-receive-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:38:01 anneks systemd[1]: ble-sensor-receive-daemon.service: Failed with result 'exit-code'. Oct 02 09:38:01 anneks sudo[1913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 09:37' Oct 02 09:38:01 anneks sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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"