Started work on an 'Onkyo AVR / Pioneer AVR / Integra AVR' custom integration

Thanks @Eddy ! Can’t wait to try it tonight. Quick question…when I downloaded it downloaded as a .tar file (not a zip). If I double click on it, it creates a folder. Should I just add .gz to the original .tar file and upload that? Thanks!

Screenshot 2025-10-28 at 2.51.23 PM

Hi @PNWUC , could you try downloading it with a different browser? Adding it back to itself will not work

If downloading it with different browser does also not deliver a .tar.gz within a zip I have to work on it some more but that I can only do in about 24hrs from now…

Downloaded with both Chrome and Safari gave the same result (on my Mac). Totally OK waiting as long as you need. I’m just super excited you’ve done this work…it’s amazing! Thanks!

Hi, okay will come back to you later. First: breakfast, work, vote (election day here in NL), dinner and then I have a bit of time to continue.

talk to you later!

Try not simply clicking on the download link. Use right mouse button and select download. This sometimes helps. If you open the file to edit and you see readable text then download failed because the gz-part was automatically expanded and you have only a tar-file which is mainly plain text and readable.

I heard several user with Chrome and Safari but upto now none from Firefox.

Ralf

1 Like

This is actually true! Clicking the download icon gave me the aforementioned .tar file. If I right click and select download, I get a .zip file. Interesting.

BUT I’m now having a different issue. When I double-click on that file the tar.gz file shows up for a split second and then disappears and a folder appears without any file extension. I’m getting closer to a tar.gz file, but not there yet!

Sorry for all the stupid feedback. This remote has introduced me to github and I’m learning all this for the first time!

It’s the browser playing games.

Did you try it using Firefox like Ralf suggested?

Yes. And using his right click trick and/or using Firefox gets me a .zip file. But then when I double click on it, a tar.gz file shows up for a split second only to disappear and be left with a folder.

EDIT: I downloaded a 3rd party application which successfully extracted the tar.gz from the zip. I’ll try the integration in a bit!

Annoying behavior :man_shrugging:

I will try to release it tonight so you can hopefully ‘just’ download it.

Oh I read now that you got it. Great! Would be good to use it for a couple of days to see it does the job or not. Im also using it.

Unfortunately, same behavior on this new version. I installed it, it worked fine, state was “standby” or “on” and able to be toggled, tested all activities, rebooted the remote, then I got errors and an “unknown” state. Only recovery is to reinstall. Sorry!

Coukd you again send me the logs?

And, does it help if you reboot twice?

Unfortunately, rebooting multiple times does not fix it.

Logs below…hopefully these are what you need. I hit character limits so I’ll separate it into 2 posts. Post 1 is the reinstall of the integration. Post 2 is the successful operation of the Onkyo and the reboot of the remote and failure of the Onkyo.

At 49sec I successfully reinstalled the integration (log below)

2025-10-29 17:49:51.140799 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] input-selector (source) set to: stm
2025-10-29 17:49:51.066289 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] power set to: STANDBY
2025-10-29 17:49:50.957047 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Subscribed entity, queue threshold set to: 100ms
2025-10-29 17:49:48.718800 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:48.717417 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:48.712606 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:48.710951 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:48.272967 +00:00 core NOTICE [uc_onkyo-avr_driver_custom.main] Waiting for available entities with request id: 9
2025-10-29 17:49:48.080551 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] audio-muting set to: undefined
2025-10-29 17:49:48.057341 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] input-selector (source) set to: stm
2025-10-29 17:49:48.030899 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] power set to: undefined
2025-10-29 17:49:47.899154 +00:00 core NOTICE Ok(DriverSetupChange { event_type: Stop, state: Ok, error: None, require_user_action: None })
2025-10-29 17:49:47.877081 +00:00 core NOTICE Sent get_entity_states to uc_onkyo-avr_driver_custom: Ok(())
2025-10-29 17:49:47.869525 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Querying AVR state after re-registration…
2025-10-29 17:49:47.868015 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Already connected to AVR, re-registering entity
2025-10-29 17:49:47.866202 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO ConfigManager: AVR at 192.168.1.84 already exists, skipping update
2025-10-29 17:49:47.865681 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Adding autodiscovered AVR with volumeScale: 100, useHalfDbSteps: true
2025-10-29 17:49:47.864531 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Discovered 1 AVR(s) via autodiscovery
2025-10-29 17:49:47.863317 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP socket closed
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO useHalfDbSteps: true
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO volumeScale: 100,
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO albumArtURL: ‘album_art.cgi’,
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO queueThreshold: 100,
2025-10-29 17:49:47.853674 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Stored setup data for autodiscovery: {
2025-10-29 17:49:47.853011 +00:00 core NOTICE Removed standby inhibitor: Integration setup: uc_onkyo-avr_driver_custom
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup data parsed - volumeScale: 100, useHalfDbSteps: true
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup data received - volumeScale raw value: ‘100’ (type: string), useHalfDbSteps: ‘true’ (type: string)
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “volumeScale”: “100”
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “useHalfDbSteps”: “true”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “queueThreshold”: “100”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “port”: “60128”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “model”: “”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “ipAddress”: “”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “albumArtURL”: “album_art.cgi”,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “setupData”: {
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “reconfigure”: true,
2025-10-29 17:49:47.848620 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup message: {
2025-10-29 17:49:47.843734 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: ===== SETUP HANDLER CALLED =====
2025-10-29 17:49:42.730604 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start node-intg@uc_onkyo-avr_driver_custom
2025-10-29 17:49:42.667546 +00:00 core NOTICE Execute system command: StartCustomIntegration(“uc_onkyo-avr_driver_custom”, Some(“node-intg@uc_onkyo-avr_driver_custom”))
2025-10-29 17:49:42.662392 +00:00 core NOTICE Added standby inhibitor: Integration setup: uc_onkyo-avr_driver_custom
2025-10-29 17:49:41.812618 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:41.811251 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:41.808058 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:41.803753 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:40.373301 +00:00 core NOTICE [uc_onkyo-avr_driver_custom.main] Waiting for available entities with request id: 6
2025-10-29 17:49:38.442775 +00:00 core NOTICE [uc_onkyo-avr_driver_custom.main] Waiting for available entities with request id: 5
2025-10-29 17:49:37.492353 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] input-selector (source) set to: stm
2025-10-29 17:49:37.156002 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] power set to: undefined
2025-10-29 17:49:37.000892 +00:00 core NOTICE Sent get_entity_states to uc_onkyo-avr_driver_custom: Ok(())
2025-10-29 17:49:36.996785 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Querying AVR state after re-registration…
2025-10-29 17:49:36.995228 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Already connected to AVR, re-registering entity
2025-10-29 17:49:36.993289 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO ConfigManager: AVR at 192.168.1.84 already exists, skipping update
2025-10-29 17:49:36.991819 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Adding autodiscovered AVR with volumeScale: 100, useHalfDbSteps: true
2025-10-29 17:49:36.990426 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Discovered 1 AVR(s) via autodiscovery
2025-10-29 17:49:36.988733 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP socket closed
2025-10-29 17:49:36.764608 +00:00 core NOTICE Ok(DriverSetupChange { event_type: Stop, state: Ok, error: None, require_user_action: None })
2025-10-29 17:49:36.726762 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Querying initial AVR state…
2025-10-29 17:49:36.725137 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Connected to AVR: model=TX-RZ1100, ip=192.168.1.84, port=60128
2025-10-29 17:49:36.706837 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100] Connecting to AVR at 192.168.1.84:60128
2025-10-29 17:49:36.697562 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Adding placeholder available entity to avoid timeouts
2025-10-29 17:49:36.688986 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Adding autodiscovered AVR with volumeScale: 100, useHalfDbSteps: true
2025-10-29 17:49:36.687341 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Discovered 1 AVR(s) via autodiscovery
2025-10-29 17:49:36.681757 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP socket closed
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO useHalfDbSteps: true
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO volumeScale: 100,
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO albumArtURL: ‘album_art.cgi’,
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO queueThreshold: 100,
2025-10-29 17:49:36.550023 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Stored setup data for autodiscovery: {
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup data parsed - volumeScale: 100, useHalfDbSteps: true
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup data received - volumeScale raw value: ‘100’ (type: string), useHalfDbSteps: ‘true’ (type: string)
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “volumeScale”: “100”
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “useHalfDbSteps”: “true”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “queueThreshold”: “100”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “port”: “60128”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “model”: “”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “ipAddress”: “”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “albumArtURL”: “album_art.cgi”,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “setupData”: {
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO “reconfigure”: false,
2025-10-29 17:49:36.542237 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Setup message: {
2025-10-29 17:49:36.538343 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: ===== SETUP HANDLER CALLED =====
2025-10-29 17:49:36.532347 +00:00 core NOTICE Removed standby inhibitor: Integration setup: uc_onkyo-avr_driver_custom
2025-10-29 17:49:33.715372 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:33.714112 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:33.710977 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:33.707142 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:31.414257 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start node-intg@uc_onkyo-avr_driver_custom
2025-10-29 17:49:31.351617 +00:00 core NOTICE Execute system command: StartCustomIntegration(“uc_onkyo-avr_driver_custom”, Some(“node-intg@uc_onkyo-avr_driver_custom”))
2025-10-29 17:49:31.346128 +00:00 core NOTICE Added standby inhibitor: Integration setup: uc_onkyo-avr_driver_custom
2025-10-29 17:49:29.123939 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] SessionAuthenticated msg
2025-10-29 17:49:29.123259 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Authenticated
2025-10-29 17:49:29.083287 +00:00 core WARN [uc_onkyo-avr_driver_custom] Failed to get integration session, not sending connect: BadRequest: Integration ID not found
2025-10-29 17:49:29.082749 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session became active: sending subscribe_events, get_driver_version, connect
2025-10-29 17:49:29.081629 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] SessionAuthenticated msg
2025-10-29 17:49:29.081047 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Authenticated
2025-10-29 17:49:29.077224 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Connected to: ws://127.0.0.1:9002
2025-10-29 17:49:28.167853 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Initializing…
2025-10-29 17:49:28.165946 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Config missing or incomplete, waiting for setup.
2025-10-29 17:49:28.159922 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Loaded config at startup: {}
2025-10-29 17:49:26.809159 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:26.807590 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:26.806874 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:26.800709 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:25.264366 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start node-intg@uc_onkyo-avr_driver_custom
2025-10-29 17:49:25.204047 +00:00 core NOTICE Execute system command: StartCustomIntegration(“uc_onkyo-avr_driver_custom”, Some(“node-intg@uc_onkyo-avr_driver_custom”))
2025-10-29 17:49:23.639563 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/install-custom-intg.sh /tmp/upload/intg uc_onkyo-avr_driver_custom 9002
2025-10-29 17:49:18.711899 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:18.710304 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:18.706618 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:18.702590 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:17.320728 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/extract-custom-intg.sh /tmp/upload/uc-intg-onkyo-avr-0.6.2.tar.gz /tmp/upload/intg
2025-10-29 17:49:11.800903 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:11.799891 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:11.799313 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:11.795122 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:05.653374 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/remove-custom-intg.sh uc_onkyo-avr_driver_custom
2025-10-29 17:49:05.579392 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] StopDriver msg
2025-10-29 17:49:03.759191 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Disconnected
2025-10-29 17:49:03.750054 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] StopDriver msg
2025-10-29 17:49:03.748987 +00:00 core NOTICE Stopping driver ‘uc_onkyo-avr_driver_custom’: no more instances

2025-10-29 17:49:29.083287 +00:00 core WARN [uc_onkyo-avr_driver_custom] Failed to get integration session, not sending connect: BadRequest: Integration ID not found
2025-10-29 17:49:29.082749 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session became active: sending subscribe_events, get_driver_version, connect
2025-10-29 17:49:29.081629 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] SessionAuthenticated msg
2025-10-29 17:49:29.081047 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Authenticated
2025-10-29 17:49:29.077224 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Connected to: ws://127.0.0.1:9002
2025-10-29 17:49:28.167853 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Initializing…
2025-10-29 17:49:28.165946 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Config missing or incomplete, waiting for setup.
2025-10-29 17:49:28.159922 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Loaded config at startup: {}
2025-10-29 17:49:26.809159 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:26.807590 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:26.806874 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:26.800709 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:25.264366 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start node-intg@uc_onkyo-avr_driver_custom
2025-10-29 17:49:25.204047 +00:00 core NOTICE Execute system command: StartCustomIntegration(“uc_onkyo-avr_driver_custom”, Some(“node-intg@uc_onkyo-avr_driver_custom”))
2025-10-29 17:49:23.639563 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/install-custom-intg.sh /tmp/upload/intg uc_onkyo-avr_driver_custom 9002
2025-10-29 17:49:18.711899 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:18.710304 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:18.706618 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:18.702590 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:17.320728 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/extract-custom-intg.sh /tmp/upload/uc-intg-onkyo-avr-0.6.2.tar.gz /tmp/upload/intg
2025-10-29 17:49:11.800903 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:49:11.799891 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:49:11.799313 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:49:11.795122 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:49:05.653374 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/opt/uc/integrations/remove-custom-intg.sh uc_onkyo-avr_driver_custom
2025-10-29 17:49:05.579392 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] StopDriver msg
2025-10-29 17:49:03.759191 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Disconnected
2025-10-29 17:49:03.750054 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] StopDriver msg
2025-10-29 17:49:03.748987 +00:00 core NOTICE Stopping driver ‘uc_onkyo-avr_driver_custom’: no more instances

At 51sec I successfully toggled the Onkyo on and off using the “Test Onkyo” activity (log below)

2025-10-29 17:51:07.266975 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] audio-muting set to: false
2025-10-29 17:51:07.186098 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] input-selector (source) set to: stm
2025-10-29 17:51:07.005156 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (receiver): [TX-RZ1100 192.168.1.84] power set to: ON
2025-10-29 17:51:06.706313 +00:00 core NOTICE Removed standby inhibitor: activity ‘Test Onkyo’ (off) (max 65s)
2025-10-29 17:51:06.705613 +00:00 core NOTICE [Test Onkyo] activity finished
2025-10-29 17:51:06.698135 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (sender): [TX-RZ1100 192.168.1.84] media-player command request: off {}
2025-10-29 17:51:06.694143 +00:00 core NOTICE [Test Onkyo] activity command: uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.off
2025-10-29 17:51:06.693333 +00:00 core NOTICE Added standby inhibitor: activity ‘Test Onkyo’ (off) (max 65s)
2025-10-29 17:51:06.689768 +00:00 core NOTICE Started activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) with timeout of 60s
2025-10-29 17:51:03.840710 +00:00 core NOTICE Removed standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-29 17:51:03.840398 +00:00 core NOTICE [Test Onkyo] activity finished
2025-10-29 17:51:03.836927 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (sender): [TX-RZ1100 192.168.1.84] media-player command request: on {}
2025-10-29 17:51:03.823947 +00:00 core NOTICE [Test Onkyo] activity command: uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.on
2025-10-29 17:51:03.823305 +00:00 core NOTICE Added standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-29 17:51:03.822689 +00:00 core NOTICE Started activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) with timeout of 60s
2025-10-29 17:51:03.821827 +00:00 core NOTICE Starting activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) in activity group: ‘Default’ (default)
2025-10-29 17:51:03.739625 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:51:03.739340 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:51:03.736013 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:51:03.734790 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:51:02.469990 +00:00 core NOTICE Setting rgb button backlight to: (191, 191, 191)
2025-10-29 17:51:02.468729 +00:00 core NOTICE Enter normal mode: standby in 1800s

At 52sec I rebooted the remote and after rebooting the Test Onkyo activity fails (and the entity now reads the status as “Unknown” (log below)

2025-10-29 17:53:14.017855 +00:00 core NOTICE Setting rgb button backlight to: (0, 0, 0)
2025-10-29 17:53:14.016428 +00:00 core NOTICE Enter low-power mode: standby in 1770s
2025-10-29 17:53:13.351566 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:53:13.350520 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:53:13.349996 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:53:13.345360 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:53:01.714558 +00:00 core ERROR Error encountered while processing the incoming HTTP request: ServiceUnavailable(“Connection to dock not established”)
2025-10-29 17:53:01.709229 +00:00 core ERROR [UCD3-0C8AB0] Error sending dock command get_sysinfo: Connection to dock not established
2025-10-29 17:53:01.678509 +00:00 core NOTICE Added standby inhibitor: Web configurator
2025-10-29 17:53:01.622596 +00:00 core ERROR Error encountered while processing the incoming HTTP request: ServiceUnavailable(“Connection to dock not established”)
2025-10-29 17:53:01.610162 +00:00 core ERROR [UCD3-0CBB00] Error sending dock command get_sysinfo: Connection to dock not established
2025-10-29 17:53:01.045622 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:58.823576 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:58.811008 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:58.782888 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:58.344388 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 5s
2025-10-29 17:52:58.343330 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:52:58.342639 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 5s
2025-10-29 17:52:58.341245 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:52:58.012960 +00:00 core NOTICE Setting rgb button backlight to: (0, 0, 0)
2025-10-29 17:52:58.011729 +00:00 core NOTICE Enter idle mode: standby in 1785s
2025-10-29 17:52:56.972245 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:53.497474 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:51.088289 +00:00 core NOTICE Removed standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-29 17:52:51.087664 +00:00 core ERROR [Test Onkyo] error executing activity command uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.on (ignore error: false): TimedOut
2025-10-29 17:52:51.084439 +00:00 core WARN [uc_onkyo-avr_driver_custom.main] Timeout while waiting for request id: 5
2025-10-29 17:52:49.959207 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:46.463535 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:46.095087 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at process.processTimers (node:internal/timers:529:7)
2025-10-29 17:52:46.095087 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at listOnTimeout (node:internal/timers:594:17)
2025-10-29 17:52:46.095087 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at Timeout._onTimeout (file:///app/eiscp.js:464:24)
2025-10-29 17:52:46.095087 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration (sender): [TX-RZ1100 192.168.1.84] Could not send command, AVR not connected: Error: Timeout waiting for AVR connection
2025-10-29 17:52:44.273273 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 4.068s
2025-10-29 17:52:44.272059 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: TimedOut
2025-10-29 17:52:44.271527 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 4.068s
2025-10-29 17:52:44.267713 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: TimedOut
2025-10-29 17:52:43.011376 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:41.081273 +00:00 core NOTICE [Test Onkyo] activity command: uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.on
2025-10-29 17:52:41.080887 +00:00 core NOTICE Added standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-29 17:52:41.080412 +00:00 core NOTICE Started activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) with timeout of 60s
2025-10-29 17:52:41.079831 +00:00 core NOTICE Starting activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) in activity group: ‘Default’ (default)
2025-10-29 17:52:40.976952 +00:00 core NOTICE Setting rgb button backlight to: (191, 191, 191)
2025-10-29 17:52:40.974671 +00:00 core NOTICE Enter normal mode: standby in 1800s
2025-10-29 17:52:40.399113 +00:00 core NOTICE Sent get_entity_states to uc_appletv_driver: Ok(())
2025-10-29 17:52:39.481274 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:39.293025 +00:00 core NOTICE Sent get_entity_states to uc_appletv_driver: Ok(())
2025-10-29 17:52:39.275463 +00:00 core NOTICE [uc_appletv_driver] SessionAuthenticated msg
2025-10-29 17:52:39.275263 +00:00 core NOTICE [uc_appletv_driver] Session state change: Authenticated
2025-10-29 17:52:39.272295 +00:00 core NOTICE [uc_appletv_driver] Session became active: sending subscribe_events, get_driver_version, connect
2025-10-29 17:52:39.271682 +00:00 core NOTICE [uc_appletv_driver] SessionAuthenticated msg
2025-10-29 17:52:39.271391 +00:00 core NOTICE [uc_appletv_driver] Session state change: Authenticated
2025-10-29 17:52:39.267673 +00:00 core NOTICE [uc_appletv_driver] Connected to: ws://127.0.0.1:8097
2025-10-29 17:52:38.010847 +00:00 core NOTICE Setting rgb button backlight to: (0, 0, 0)
2025-10-29 17:52:38.010129 +00:00 core NOTICE Enter idle mode: standby in 1785s
2025-10-29 17:52:35.957506 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:35.934003 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:35.918302 +00:00 core ERROR Error encountered while processing the incoming HTTP request: AuthError(“Authentication error”)
2025-10-29 17:52:35.916602 +00:00 core NOTICE No session state has been found for a valid session key, creating a new empty session.
2025-10-29 17:52:35.189677 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:35.102879 +00:00 core WARN [UCD3-0CBB00] Can’t handle dock TryReconnect command in state: Connecting
2025-10-29 17:52:35.101689 +00:00 core WARN [UCD3-0CBB00] No transition for: state=Connecting, event=TryReconnect
2025-10-29 17:52:35.101191 +00:00 core WARN [UCD3-0C8AB0] Can’t handle dock TryReconnect command in state: Connecting
2025-10-29 17:52:35.097089 +00:00 core WARN [UCD3-0C8AB0] No transition for: state=Connecting, event=TryReconnect
2025-10-29 17:52:35.108812 +00:00 core NOTICE [UCD3-0CBB00] WiFi connected: connecting dock
2025-10-29 17:52:35.108445 +00:00 core NOTICE [UCD3-0C8AB0] WiFi connected: connecting dock
2025-10-29 17:52:35.107569 +00:00 core NOTICE WiFi interface is up and routable, sending connected event
2025-10-29 17:52:35.105732 +00:00 core NOTICE Network changed: link_name: wlan0, operational_state: routable, address_state: routable, ipv4_address_state: routable, online_state: online
2025-10-29 17:52:34.558977 +00:00 core NOTICE Ignoring connected event from WiFi driver, using operational state instead
2025-10-29 17:52:34.372929 +00:00 core NOTICE Network changed: link_name: wlan0, operational_state: carrier, carrier_state: carrier
2025-10-29 17:52:34.266980 +00:00 core NOTICE Network changed: link_name: wlan0, operational_state: dormant, carrier_state: dormant
2025-10-29 17:52:33.412515 +00:00 core NOTICE Changed battery status: 95% Discharging, 3973mV, charger: false
2025-10-29 17:52:32.899702 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:32.809541 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 3.13s
2025-10-29 17:52:32.809319 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:32.809050 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 3.13s
2025-10-29 17:52:32.808785 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:32.152315 +00:00 core NOTICE WifiEvent: NETWORK_NOT_FOUND
2025-10-29 17:52:30.490071 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:30.402970 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 2.408s
2025-10-29 17:52:30.402521 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:30.402037 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 2.408s
2025-10-29 17:52:30.401492 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:29.470993 +00:00 core NOTICE Sent get_entity_states to sonyadcp: Ok(())
2025-10-29 17:52:29.431513 +00:00 core NOTICE [sonyadcp] Session became active: sending subscribe_events, get_driver_version, connect
2025-10-29 17:52:29.431214 +00:00 core NOTICE [sonyadcp] SessionAuthenticated msg
2025-10-29 17:52:29.430926 +00:00 core NOTICE [sonyadcp] Session state change: Authenticated
2025-10-29 17:52:29.429440 +00:00 core NOTICE [sonyadcp] SessionAuthenticated msg
2025-10-29 17:52:29.429238 +00:00 core NOTICE [sonyadcp] Session state change: Authenticated
2025-10-29 17:52:29.425300 +00:00 core NOTICE [sonyadcp] Connected to: ws://127.0.0.1:9001
2025-10-29 17:52:29.001830 +00:00 core NOTICE Sent get_entity_states to uc_onkyo-avr_driver_custom: Ok(())
2025-10-29 17:52:28.990254 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Querying AVR state after re-registration…
2025-10-29 17:52:28.989771 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Already connected to AVR, re-registering entity
2025-10-29 17:52:28.989771 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Discovered 0 AVR(s) via autodiscovery
2025-10-29 17:52:28.981705 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP socket closed
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO port: 60128
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO address: ‘255.255.255.255’,
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO syscall: ‘send’,
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO code: ‘ENETUNREACH’,
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO errno: -101,
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at process.processTicksAndRejections (node:internal/process/task_queues:91:21) {
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at afterDns (node:dgram:699:5)
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18)
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at doSend (node:dgram:760:16)
2025-10-29 17:52:28.980573 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP send error (network may not be ready): Error: send ENETUNREACH 255.255.255.255:60128
2025-10-29 17:52:28.969739 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Subscribed entity, queue threshold set to: 100ms
2025-10-29 17:52:28.953727 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] SessionAuthenticated msg
2025-10-29 17:52:28.953405 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Authenticated
2025-10-29 17:52:28.924116 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session became active: sending subscribe_events, get_driver_version, connect
2025-10-29 17:52:28.923540 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] SessionAuthenticated msg
2025-10-29 17:52:28.923155 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Session state change: Authenticated
2025-10-29 17:52:28.917907 +00:00 core NOTICE [uc_onkyo-avr_driver_custom] Connected to: ws://127.0.0.1:9002
2025-10-29 17:52:28.758886 +00:00 core ERROR [sonyadcp] Error connecting to driver ws://127.0.0.1:9001: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:28.683332 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:28.652058 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Querying initial AVR state…
2025-10-29 17:52:28.652058 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Connected to AVR: model=TX-RZ1100, ip=192.168.1.84, port=60128
2025-10-29 17:52:28.618914 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100] Connecting to AVR at 192.168.1.84:60128
2025-10-29 17:52:28.618914 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: [TX-RZ1100 192.168.1.84] Adding placeholder available entity to avoid timeouts
2025-10-29 17:52:28.618914 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Discovered 0 AVR(s) via autodiscovery
2025-10-29 17:52:28.596086 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP socket closed
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO port: 60128
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO address: ‘255.255.255.255’,
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO syscall: ‘send’,
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO code: ‘ENETUNREACH’,
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO errno: -101,
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at process.processTicksAndRejections (node:internal/process/task_queues:91:21) {
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at afterDns (node:dgram:699:5)
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18)
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO at doSend (node:dgram:760:16)
2025-10-29 17:52:28.586594 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO [EiscpDriver] UDP send error (network may not be ready): Error: send ENETUNREACH 255.255.255.255:60128
2025-10-29 17:52:28.548158 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 1.853s
2025-10-29 17:52:28.543286 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:28.541002 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 1.853s
2025-10-29 17:52:28.540378 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:28.432794 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Onkyo-Integration: Initializing…
2025-10-29 17:52:28.410385 +00:00 core NOTICE Added standby inhibitor: charger
2025-10-29 17:52:28.410072 +00:00 core NOTICE Changed battery status: 96% Discharging, 3974mV, charger: false
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO ]
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO }
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO useHalfDbSteps: true
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO volumeScale: 100,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO albumArtURL: ‘album_art.cgi’,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO queueThreshold: 100,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO port: 60128,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO ip: ‘192.168.1.84’,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO model: ‘TX-RZ1100’,
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO {
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO avrs: [
2025-10-29 17:52:28.356199 +00:00 node-intg-uc_onkyo-avr_driver_custom INFO Loaded config at startup: {
2025-10-29 17:52:28.327356 +00:00 core ERROR Error starting custom integration session: Failed to connect to host: Connection refused (os error 111)
2025-10-29 17:52:28.273093 +00:00 core ERROR [uc_onkyo-avr_driver_custom] Error connecting to driver ws://127.0.0.1:9002: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:28.264771 +00:00 core ERROR Error starting custom integration session: Failed to connect to host: Connection refused (os error 111)
2025-10-29 17:52:28.254120 +00:00 core ERROR [sonyadcp] Error connecting to driver ws://127.0.0.1:9001: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:27.206754 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:27.147108 +00:00 core NOTICE BtDaemonStatus { status: Working, hci_log: true, log_level: Debug, connections: 0, max_connections: 1, paired_profiles: 0, active_profile: 0, advertising: true, advertising_name: “Remote3 B07BD5”, version: “0.6.0” }
2025-10-29 17:52:27.140066 +00:00 core NOTICE [BT] Scan mode: discoverable=false, connectable=false
2025-10-29 17:52:27.113767 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 1.426s
2025-10-29 17:52:27.113401 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:27.112026 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 1.426s
2025-10-29 17:52:27.109352 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:26.102885 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:26.091372 +00:00 core NOTICE BtDaemonStatus { status: Initializing, hci_log: true, log_level: Debug, connections: 0, max_connections: 1, paired_profiles: 0, active_profile: 0, advertising: true, advertising_name: “Remote3 000000”, version: “0.6.0” }
2025-10-29 17:52:26.090038 +00:00 core NOTICE Connected to BT daemon: 127.0.0.1:13333
2025-10-29 17:52:26.084297 +00:00 core NOTICE Restarting BT daemon client, enabled=true
2025-10-29 17:52:26.010722 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 1.097s
2025-10-29 17:52:26.010464 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:26.010218 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 1.097s
2025-10-29 17:52:26.010020 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:25.258209 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:25.168597 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 844ms
2025-10-29 17:52:25.168376 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:25.168091 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 844ms
2025-10-29 17:52:25.167462 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:24.933108 +00:00 core ERROR Cannot connect to BT daemon: I/O error. Reconnecting in 1.148741523s
2025-10-29 17:52:24.932592 +00:00 core NOTICE Restarting BT daemon client, enabled=true
2025-10-29 17:52:24.929423 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start node-intg@uc_onkyo-avr_driver_custom
2025-10-29 17:52:24.876448 +00:00 core NOTICE Execute system command: StartCustomIntegration(“uc_onkyo-avr_driver_custom”, Some(“node-intg@uc_onkyo-avr_driver_custom”))
2025-10-29 17:52:24.604535 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:24.517519 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 650ms
2025-10-29 17:52:24.517304 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:24.517067 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 650ms
2025-10-29 17:52:24.516537 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:24.446420 +00:00 core ERROR Cannot connect to BT daemon: I/O error. Reconnecting in 523.517131ms
2025-10-29 17:52:24.444486 +00:00 core NOTICE Restarting BT daemon client, enabled=true
2025-10-29 17:52:24.384870 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start custom-intg@sonyadcp
2025-10-29 17:52:24.250148 +00:00 core NOTICE Execute system command: StartCustomIntegration(“sonyadcp”, Some(“custom-intg@sonyadcp”))
2025-10-29 17:52:24.234947 +00:00 core NOTICE Not starting driver ‘uc_roku_driver’: no configured instances
2025-10-29 17:52:24.214487 +00:00 core NOTICE Not starting driver ‘uc_bo_driver’: no configured instances
2025-10-29 17:52:24.212759 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/sbin/wl country US
2025-10-29 17:52:24.202110 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start wpa_supplicant
2025-10-29 17:52:24.195970 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/sbin/wl band b
2025-10-29 17:52:24.184709 +00:00 core NOTICE uccore : PWD=/opt/uc/data ; USER=root ; COMMAND=/usr/bin/systemctl start btstack
2025-10-29 17:52:24.110520 +00:00 core NOTICE Not starting driver ‘uc_roon_driver’: no configured instances
2025-10-29 17:52:24.108719 +00:00 core NOTICE Not starting driver ‘uc_androidtv_driver’: no configured instances
2025-10-29 17:52:24.102152 +00:00 core ERROR [uc_appletv_driver] Error connecting to driver ws://127.0.0.1:8097: ServiceUnavailable(“Failed to connect to host: Connection refused (os error 111)”)
2025-10-29 17:52:24.098239 +00:00 core NOTICE Not starting driver ‘uc_sonos_driver’: no configured instances
2025-10-29 17:52:24.092443 +00:00 core NOTICE Not starting driver ‘uc_gc_driver’: no configured instances
2025-10-29 17:52:24.087939 +00:00 core NOTICE Not starting driver ‘uc_hue_driver’: no configured instances
2025-10-29 17:52:24.082322 +00:00 core NOTICE Not starting driver ‘hass’: no configured instances
2025-10-29 17:52:24.080347 +00:00 core NOTICE Not starting driver ‘uc_denon_driver’: no configured instances
2025-10-29 17:52:24.013197 +00:00 core NOTICE [UCD3-0CBB00] Reconnecting in: 500ms
2025-10-29 17:52:24.012539 +00:00 core ERROR [UCD3-0CBB00] Error connecting to dock ws://192.168.1.79/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:24.011873 +00:00 core NOTICE [UCD3-0C8AB0] Reconnecting in: 500ms
2025-10-29 17:52:24.009032 +00:00 core ERROR [UCD3-0C8AB0] Error connecting to dock ws://192.168.1.29/ws: ServiceUnavailable(“Failed to connect to host: Network is unreachable (os error 101)”)
2025-10-29 17:52:23.997780 +00:00 core NOTICE Created Dock IR emitter: dock_id=UCD3-0C8AB0, name=‘Charger’, model=UCD3, active=true, port_mask=0
2025-10-29 17:52:23.997566 +00:00 core NOTICE [UCD3-0C8AB0] Resolved ws url: ws://192.168.1.29/ws
2025-10-29 17:52:23.997348 +00:00 core NOTICE Created Dock IR emitter: dock_id=UCD3-0CBB00, name=‘Closet’, model=UCD3, active=true, port_mask=0
2025-10-29 17:52:23.997054 +00:00 core NOTICE [UCD3-0CBB00] Resolved ws url: ws://192.168.1.79/ws
2025-10-29 17:52:23.964592 +00:00 core ERROR Cannot connect to BT daemon: I/O error. Reconnecting in 441.786122ms
2025-10-29 17:52:23.951945 +00:00 core NOTICE Execute system command: StartBluetooth
2025-10-29 17:52:23.950578 +00:00 core NOTICE Execute system command: StartWifi
2025-10-29 17:52:23.950070 +00:00 core NOTICE Execute system command: EnableWoWLAN(true)
2025-10-29 17:52:23.948789 +00:00 core NOTICE Execute system command: SetWifiBand(B)
2025-10-29 17:52:23.948338 +00:00 core NOTICE Updating BT service configuration file, connections=1
2025-10-29 17:52:23.936156 +00:00 core NOTICE Feature flag enabled: routable_if_link. Using operational state ‘routable’ for WiFi connect events.
2025-10-29 17:52:23.926158 +00:00 core NOTICE Monitoring network link ‘wlan0’ for changes
2025-10-29 17:52:23.856867 +00:00 core NOTICE Setting rgb button backlight to: (191, 191, 191)
2025-10-29 17:52:23.852703 +00:00 core NOTICE Execute system command: SetWlCommand(“country”, “US”)
2025-10-29 17:52:23.850162 +00:00 core NOTICE Enter normal mode: standby in 1800s
2025-10-29 17:52:23.578134 +00:00 core NOTICE starting service: “actix-web-service-127.0.0.1:9000”, workers: 1, listening on: 127.0.0.1:9000
2025-10-29 17:52:23.577932 +00:00 core NOTICE Actix runtime found; starting in Actix runtime
2025-10-29 17:52:23.506364 +00:00 core NOTICE Feature flag enabled: routable_if_link. Using operational state ‘routable’ for WiFi connect events.
2025-10-29 17:52:23.159974 +00:00 core NOTICE starting 1 workers
2025-10-29 17:52:22.687853 +00:00 core NOTICE Cleaning up downloaded system OTA updates <= 2.7.0
2025-10-29 17:52:22.679866 +00:00 core NOTICE Core-API listening on: 127.0.0.1:9000

Thanks!
Could you try 0.6.3 (when you have time and energy for it :slight_smile: ) I have not released it but you can find it here.
After installation and setup, see if it works, then reboot the remote, wait a few seconds until the little ball in top of the screen of the remote (next to battery logo) is not moving left/right anymore and then start an activity.

If this also does not solve it, it will take more time to figure it out because this is the only thing I could still think of at this moment.

Fingers crossed :crossed_fingers:

Time and energy…testing is easy! I thank YOU for your time and energy to get this up and running!

Bad news. Same behavior. Installed 0.6.3. Worked fine. Rebooted remote. Waited until ball stopped moving. Error trying the same activity. Here the log of the failed attempt:

2025-10-30 00:25:12.510513 +00:00 core NOTICE Removed standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-30 00:25:12.509923 +00:00 core ERROR [Test Onkyo] error executing activity command uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.on (ignore error: false): TimedOut
2025-10-30 00:25:12.508213 +00:00 core WARN [uc_onkyo-avr_driver_custom.main] Timeout while waiting for request id: 5
2025-10-30 00:25:02.504707 +00:00 core NOTICE [Test Onkyo] activity command: uc_onkyo-avr_driver_custom.main.TX-RZ1100 192.168.1.84:media_player.on
2025-10-30 00:25:02.504199 +00:00 core NOTICE Added standby inhibitor: activity ‘Test Onkyo’ (on) (max 65s)
2025-10-30 00:25:02.503676 +00:00 core NOTICE Started activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) with timeout of 60s
2025-10-30 00:25:02.502850 +00:00 core NOTICE Starting activity ‘Test Onkyo’ (uc.main.770bdf25-57b0-4711-a02f-7eaa6a7572f4) in activity group: ‘Default’ (default)

Also, probably won’t help any, but the screen is different before and after reboot. As you can see below, before reboot the entire entity name is shown under Step 1/1. After the reboot the entity name is gone. That may be nothing, but thought I’d provide the additional data point.

Thanks for that info! I have reinstalled, power down/up, rebooted my remote many times but cannot replicate the issue.

The screenshots tell me I have to look into a different direction. Will chew on it some more and come back to you later this week.

Thank you so much for your efforts @Eddy ! Good news is that as long as I don’t reboot the remote, it works great!

Hopefully something becomes obvious as you try and troubleshoot…I love not having to rely on IR! What a weird bug!

Thank you for trying and reporting @PNWUC !

It’s indeed weird, and interesting :smiley:

But we will get it fixed one way or the other.

One question: could you install 0.6.0 again and see if the issue now also is there while running 0.6.0?

Because I cannot reproduce the issue and nobody else reported it, I think something went wrong on the remote during uninstall of 0.6.0 or install of 0.6.1 that causes this. Could you try 0.6.0 and see if that now also does not survive a reboot? No need for new logs, just would like to know what would happen.