plugin crashes homebridge

[thayneq]

Hi,

I really love the idea of this plugin. I have two Pioneer receivers, and I am unable to get this plugin working with either.

I also have two computers I've tested this on: raspi, and synology.

In all four instances, whenever this plugin is present in the config.json file, homebridge stops communicating with HomeKit entirely. There is nothing suspicious in the log files either. The plugin is able to scrape the input list of my receivers and it's communicating properly. Occasionally I will see a message that homebridge received a SIGINT and reboots. But regardless, the only way I can get homebridge to bring back all my accessories is to disable this plugin. I also notice that with this plugin, if you delete the persist folder and try to re-associate homebridge with homekit, it will not allow it.

[thayneq]

I know this isn't a very helpful comment, but if you need me to provide any log files, etc, please let me know!

[kazcangi]

Hi,

This is interesting, in my case I have only the pioneer-avr plugin and the config-ui-x in my config.json. Could you start home bridge in debug mode (https://github.com/nfarina/homebridge/wiki/Basic-Troubleshooting), and attach log file ? And your config.json too ?

[kazcangi]

Which iOS version do you have ?

[thayneq]

I'm running 12.4 beta on my iPhone, and 12.3.1 on my iPad.

[kazcangi]

Which AVR models do you have ? Can you post debug logs in order to investigate ? Thanks

[kazcangi]

Hi,

Can you test with the version 0.8 ? The connection with AVR has been rewritten.

[kazcangi]

I run homebridge since 10 days for now. I have no more crash like yours. If there is no problem in the next 5 days, I will close this issue

[Mikha495]

Tried to connect the plug-in today. Have the same issue: Homebridge crashes ones I modify the config.json. I have a Pioneer VSX 923-S

[kazcangi]

Thanks for your feedback. It's hard to investigate as I can't reproduce your problem. Could you restart Homebridge in debug mode and attach log files ?

[Mikha495]

hoobs@hoobs:/var/lib/homebridge $ DEBUG=* homebridge -D [9/3/2019, 10:38:30 PM] config.json (/home/hoobs/.homebridge/config.json) not found. [9/3/2019, 10:38:31 PM] Loaded plugin: homebridge-config-ui-x [9/3/2019, 10:38:31 PM] Registering platform 'homebridge-config-ui-x.config' [9/3/2019, 10:38:31 PM] --- [9/3/2019, 10:38:32 PM] Loaded plugin: homebridge-hue [9/3/2019, 10:38:32 PM] Registering platform 'homebridge-hue.Hue' [9/3/2019, 10:38:32 PM] --- [9/3/2019, 10:38:32 PM] Loaded plugin: homebridge-pioneer-avr [9/3/2019, 10:38:32 PM] Registering accessory 'homebridge-pioneer-avr.pioneerAvrAccessory' [9/3/2019, 10:38:32 PM] --- [9/3/2019, 10:38:32 PM] Loaded plugin: homebridge-shelly-shutter [9/3/2019, 10:38:32 PM] Registering accessory 'homebridge-shelly-shutter.shelly-shutter' [9/3/2019, 10:38:32 PM] --- [9/3/2019, 10:38:33 PM] Loaded plugin: homebridge-xiaomi-roborock-vacuum [9/3/2019, 10:38:33 PM] Registering accessory 'homebridge-xiaomi-roborock-vacuum.XiaomiRoborockVacuum' [9/3/2019, 10:38:33 PM] ---

here you go. I'm using Hoobs. Seems like your plugin overrides the path to the config.json file, hence it can't be found anymore.

Edit: I can recall that the plugins are loading, also the Pioneer plug-in loads and set up itself, though Homebridge can’t start. So not sure, why the log writes that config.json can’t be found...

[kazcangi]

Ok thanks. I’ll install hoobs to test the plugin with it. I’m actually using raspbian with homebridge

[Mikha495]

Thanks! Wait for your feedback

[ghost]

I have the same issue with a VSX-45. As soon as I add the accessory it never starts home bridge -- no real error just doesn't continue loading.

[9/6/2019, 11:11:15 PM] Loaded config.json with 1 accessories and 3 platforms. [9/6/2019, 11:11:15 PM] --- [9/6/2019, 11:11:15 PM] Loaded plugin: homebridge-config-ui-x [9/6/2019, 11:11:15 PM] Registering platform 'homebridge-config-ui-x.config' [9/6/2019, 11:11:15 PM] --- [9/6/2019, 11:11:15 PM] Loaded plugin: homebridge-envisakit [9/6/2019, 11:11:15 PM] Registering accessory 'homebridge-envisakit.Envisakit' [9/6/2019, 11:11:15 PM] --- [9/6/2019, 11:11:15 PM] Loaded plugin: homebridge-envisalink [9/6/2019, 11:11:15 PM] Registering platform 'homebridge-envisalink.Envisalink' [9/6/2019, 11:11:15 PM] --- [9/6/2019, 11:11:16 PM] Loaded plugin: homebridge-harmony [9/6/2019, 11:11:16 PM] Registering platform 'homebridge-harmonyHub.HarmonyHubWebSocket' [9/6/2019, 11:11:16 PM] --- [9/6/2019, 11:11:16 PM] Loaded plugin: homebridge-pioneer-avr [9/6/2019, 11:11:16 PM] Registering accessory 'homebridge-pioneer-avr.pioneerAvrAccessory' [9/6/2019, 11:11:16 PM] --- [9/6/2019, 11:11:16 PM] Loaded plugin: homebridge-ring [9/6/2019, 11:11:16 PM] Registering platform 'homebridge-ring.Ring' [9/6/2019, 11:11:16 PM] --- [9/6/2019, 11:11:16 PM] Loading 3 platforms... [9/6/2019, 11:11:16 PM] [Config] Initializing config platform... [9/6/2019, 11:11:16 PM] [Config] Spawning homebridge-config-ui-x with PID 54307 [9/6/2019, 11:11:16 PM] [Harmony Hub] Initializing HarmonyHubWebSocket platform... [9/6/2019, 11:11:16 PM] [Harmony Hub] HarmonyPlatform Init [9/6/2019, 11:11:16 PM] [Ring] Initializing Ring platform... [9/6/2019, 11:11:16 PM] Loading 1 accessories... [9/6/2019, 11:11:16 PM] [AV Receiver] Initializing pioneerAvrAccessory accessory... [9/6/2019, 11:11:16 PM] [AV Receiver] Discovering inputs [9/6/2019, 11:11:16 PM] [AV Receiver] Web Interface enabled [9/6/2019, 11:11:18 PM] [Config] Homebridge Config UI X v4.6.1 is listening on :: port 8001

and then doesn't continue on. Homebridge never starts. Take out the accessory entry and everything is good again. This is homebridge running directly on MacOS, not a pi.

[kazcangi]

Hi,

Thanks for your feedback. I've just installed Homebridge on my Mac, with only the pioneer plugin and it works. I think the problem come from the RGB command on the AVR. Could you try to telnet to you AVR and type :

RGB05

And paste the result ? Beware to cleanly quit telnet session (with ^]) unless you'll probably need to power cycle you AVR.

Edit : This command is a "new command" in the VSX-1120-K-RS232.PDF document from pioneer. It my not be available on all AVR. Also check the network standby option from your AVR. From the documentation :

About Network Standby
This setting allows the IP Control function
for operating the receiver from a IP control system connected on the same LAN
as the receiver to be used even when the receiver is in the standby mode.
1. Select ‘Network Standby’ from the Network Setup menu.
2. Then set to "ON".

[ghost]

If I'm doing this right, this is what I got back:

MacBook-Pro:~$ telnet 192.168.173.40 Trying 192.168.173.40... Connected to *****. Escape character is '^]'. BridgeCo AG Telnet server

RGB05 E04

[kazcangi]

You dit this right. Sorry but I made a mistake in the command. Can you do the same with the following one ?

?RGB05

[Mikha495]

Perfect! Just went into the network settings of the receiver and set the "Network Standby" to ON. Reinstalled the Plug-In and everything seems to work. Need to test out a bit, but Homebridge has loaded successfully and I could turn on and of the Pioneer through HomeKit!

[ghost]

You dit this right. Sorry but I made a mistake in the command. Can you do the same with the following one ?

?RGB05

BridgeCo AG Telnet server ?RGB05 RGB051TV

I also enabled Network Standby as well... not sure it helped in my case though.

[ghost]

It's closer now but now it hangs in a different spot

[9/8/2019, 9:56:46 AM] Loading 3 platforms... [9/8/2019, 9:56:46 AM] [Config] Initializing config platform... [9/8/2019, 9:56:46 AM] [Config] Spawning homebridge-config-ui-x with PID 21196 [9/8/2019, 9:56:46 AM] [Harmony Hub] Initializing HarmonyHubWebSocket platform... [9/8/2019, 9:56:46 AM] [Harmony Hub] HarmonyPlatform Init [9/8/2019, 9:56:46 AM] [Ring] Initializing Ring platform... [9/8/2019, 9:56:46 AM] Loading 1 accessories... [9/8/2019, 9:56:46 AM] [AV Receiver] Initializing pioneerAvrAccessory accessory... [9/8/2019, 9:56:46 AM] [AV Receiver] Discovering inputs [9/8/2019, 9:56:46 AM] [AV Receiver] Web Interface enabled [9/8/2019, 9:56:47 AM] [AV Receiver] Add input n°0 - Name: MULTI CH IN Id: 12 Type: 0 [9/8/2019, 9:56:47 AM] [AV Receiver] Add input n°1 - Name: USB-DAC Id: 13 Type: 0 [9/8/2019, 9:56:47 AM] [AV Receiver] Add input n°2 - Name: DVR/BDR Id: 15 Type: 3 [9/8/2019, 9:56:48 AM] [AV Receiver] Add input n°3 - Name: iPod/USB Id: 17 Type: 9 [9/8/2019, 9:56:48 AM] [Config] Homebridge Config UI X v4.6.1 is listening on :: port 8001 [9/8/2019, 9:56:48 AM] [AV Receiver] Add input n°4 - Name: HDMI1 Id: 19 Type: 3 [9/8/2019, 9:56:48 AM] [AV Receiver] Add input n°5 - Name: HDMI2 Id: 20 Type: 3 [9/8/2019, 9:56:49 AM] [AV Receiver] Add input n°6 - Name: HDMI3 Id: 21 Type: 3 [9/8/2019, 9:56:49 AM] [AV Receiver] Add input n°7 - Name: HDMI4 Id: 22 Type: 3 [9/8/2019, 9:56:49 AM] [AV Receiver] Add input n°8 - Name: HDMI5 Id: 23 Type: 3 [9/8/2019, 9:56:49 AM] [AV Receiver] Add input n°9 - Name: HDMI 6 Id: 24 Type: 3 [9/8/2019, 9:56:50 AM] [AV Receiver] Add input n°10 - Name: XboxOne UHD-BD Id: 25 Type: 3 [9/8/2019, 9:56:51 AM] [AV Receiver] Add input n°11 - Name: BT AUDIO Id: 33 Type: 0 [9/8/2019, 9:56:51 AM] [AV Receiver] Add input n°12 - Name: HDMI 7 Id: 34 Type: 3 [9/8/2019, 9:56:51 AM] [AV Receiver] Add input n°13 - Name: INTERNET RADIO Id: 38 Type: 2 [9/8/2019, 9:56:52 AM] [AV Receiver] Add input n°14 - Name: PANDORA Id: 41 Type: 0 [9/8/2019, 9:56:52 AM] [AV Receiver] Add input n°15 - Name: MEDIA SERVER Id: 44 Type: 0 [9/8/2019, 9:56:52 AM] [AV Receiver] Add input n°16 - Name: FAVORITES Id: 45 Type: 0 [9/8/2019, 9:56:53 AM] [AV Receiver] Add input n°17 - Name: PHONO Id: 00 Type: 0 [9/8/2019, 9:56:53 AM] [AV Receiver] Add input n°18 - Name: CD Id: 01 Type: 0 [9/8/2019, 9:56:54 AM] [AV Receiver] Add input n°19 - Name: TUNER Id: 02 Type: 2

[kazcangi]

@xzi can you start Homebridge with the following command ?

DEBUG=* homebridge -D

[alexcarsso]

i have this problem to, this is my log with command DEBUG=* home bridge -D

[2019-9-12 10:04:56 PM] [My Pioneer AVR] Input [CD] discovered (id: 01, type: 0). InitCount=30/35 [2019-9-12 10:04:56 PM] [My Pioneer AVR] Add input n°18 - Name: CD Id: 01 Type: 0 [2019-9-12 10:04:56 PM] [My Pioneer AVR] Receive data : RGB020TUNER [2019-9-12 10:04:56 PM] [My Pioneer AVR] Input [TUNER] discovered (id: 02, type: 2). InitCount=31/35 [2019-9-12 10:04:56 PM] [My Pioneer AVR] Add input n°19 - Name: TUNER Id: 02 Type: 2 [2019-9-12 10:04:56 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:57 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:57 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:57 PM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-12 10:04:57 PM] [Music] applescript result: {"on":true,"volume":40,"track":"bad guy","speakers":{"Computer":{"on":false,"volume":27},"Salone":{"on":true,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"STEREO ":{"on":false,"volume":39}}} [2019-9-12 10:04:58 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:58 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:59 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:04:59 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:00 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:00 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:01 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:01 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:02 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:02 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:03 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:03 PM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-12 10:05:03 PM] [Music] applescript result: {"on":true,"volume":40,"track":"IL CIELO NELLA STANZA (feat. NSTASIA)","speakers":{"Computer":{"on":false,"volume":27},"Salone":{"on":true,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"STEREO ":{"on":false,"volume":39}}} [2019-9-12 10:05:03 PM] [Music] Music: track changed from "bad guy" to "IL CIELO NELLA STANZA (feat. NSTASIA)" [2019-9-12 10:05:03 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:04 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:04 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:05 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:05 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:06 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:06 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:07 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:07 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:07 PM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-12 10:05:07 PM] [Music] applescript result: {"on":true,"volume":40,"track":"IL CIELO NELLA STANZA (feat. NSTASIA)","speakers":{"Computer":{"on":false,"volume":27},"Salone":{"on":true,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"STEREO ":{"on":false,"volume":39}}} [2019-9-12 10:05:08 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:08 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:09 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-12 10:05:09 PM] [My Pioneer AVR] Waiting for pioneerAvrAccessory to be ready

[kazcangi]

Seems that the AVR return an unexpected value. Could you try to replace, in the pioneer-avr.js file, at line 256 :

    try {
        this.log.debug('Send command : %s', command);
        data = await this.s.sendMessage(command);
        this.log.debug('Receive data : %s', data);
    } catch (e) {
        this.log.error(e)
    }

with

    try {
        this.log.info('Send command : %s', command);
        data = await this.s.sendMessage(command);
        this.log.info('Receive data : %s', data);
    } catch (e) {
        this.log.error(e)
    }

and restart homebridge ? I have to know what value is returned by the AVR.

Thanks

[alexcarsso]

Hi, thanks for the prompt reply, I modified the file with your instructions and re-launched the command "DEBUG = * homebridge -D". What follows is the log, it waits as you see and doesn't go on

Mini-di-User:~ user$ DEBUG=* homebridge -D [2019-9-17 11:36:26 AM] Loaded config.json with 11 accessories and 1 platforms. [2019-9-17 11:36:26 AM] --- [2019-9-17 11:36:26 AM] Loaded plugin: homebridge-applescript-file [2019-9-17 11:36:26 AM] Registering accessory 'homebridge-applescript-file.ApplescriptFile' [2019-9-17 11:36:26 AM] --- [2019-9-17 11:36:26 AM] Loaded plugin: homebridge-music [2019-9-17 11:36:26 AM] Registering platform 'homebridge-music.Music' [2019-9-17 11:36:26 AM] --- [2019-9-17 11:36:26 AM] Loaded plugin: homebridge-pioneer-avr [2019-9-17 11:36:26 AM] Registering accessory 'homebridge-pioneer-avr.pioneerAvrAccessory' [2019-9-17 11:36:26 AM] --- [2019-9-17 11:36:26 AM] Loading 1 platforms... [2019-9-17 11:36:26 AM] [Music] Initializing Music platform... [2019-9-17 11:36:26 AM] [Music] homebridge-music v0.2.9, node v10.16.0, homebridge v0.4.50 [2019-9-17 11:36:26 AM] [Music] warning: not using recommended homebridge version v0.4.49 [2019-9-17 11:36:26 AM] [Music] config.json: {"platform":"Music"} [2019-9-17 11:36:26 AM] Loading 11 accessories... [2019-9-17 11:36:26 AM] [Pioneer AVR] Initializing pioneerAvrAccessory accessory... [2019-9-17 11:36:26 AM] [Pioneer AVR] Preferences directory : /Users/user/Library/Preferences/pioneerAvr/ [2019-9-17 11:36:26 AM] [Pioneer AVR] Input visibility file does not exist [2019-9-17 11:36:26 AM] [Pioneer AVR] Discovering inputs [2019-9-17 11:36:26 AM] [Pioneer AVR] Discovering inputs [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 10 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB10 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 12 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB12 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 13 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB13 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 14 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB14 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 15 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB15 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 17 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB17 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 18 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB18 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 19 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB19 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 20 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB20 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 21 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB21 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 22 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB22 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 23 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB23 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 24 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB24 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 25 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB25 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 26 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB26 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 27 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB27 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 31 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB31 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 33 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB33 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 34 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB34 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 35 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB35 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 38 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB38 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 40 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB40 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 41 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB41 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 44 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB44 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 45 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB45 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 48 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB48 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 49 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB49 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 57 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB57 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 00 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB00 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 01 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB01 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 02 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB02 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 03 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB03 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 04 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB04 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 05 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB05 [2019-9-17 11:36:26 AM] [Pioneer AVR] Trying Input key: 06 [2019-9-17 11:36:26 AM] [Pioneer AVR] Send command : ?RGB06 [2019-9-17 11:36:26 AM] [Pioneer AVR] Web Interface enabled [2019-9-17 11:36:26 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:26 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:26 AM] [Pioneer AVR] Input does not exists. InitCount=1/35 [2019-9-17 11:36:26 AM] [Pioneer AVR] Receive data : RGB120MULTI CH IN [2019-9-17 11:36:26 AM] [Pioneer AVR] Input [MULTI CH IN] discovered (id: 12, type: 0). InitCount=2/35 [2019-9-17 11:36:26 AM] [Pioneer AVR] Add input n°0 - Name: MULTI CH IN Id: 12 Type: 0 [2019-9-17 11:36:26 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:26 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'Music'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'Computer'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'AirPort Express old di Alessandro'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'AirPort Express di Mamma'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'AS-AFTT[AirPlay]'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'Matrimoniale'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'Matteo Apple TV'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'Salone'... [2019-9-17 11:36:26 AM] [Music] Initializing platform accessory 'STEREO '... [2019-9-17 11:36:26 AM] [Pioneer AVR] Receive data : RGB130USB-DAC [2019-9-17 11:36:26 AM] [Pioneer AVR] Input [USB-DAC] discovered (id: 13, type: 0). InitCount=3/35 [2019-9-17 11:36:26 AM] [Pioneer AVR] Add input n°1 - Name: USB-DAC Id: 13 Type: 0 [2019-9-17 11:36:27 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:27 AM] [Pioneer AVR] Input does not exists. InitCount=4/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : RGB150DVR/BDR [2019-9-17 11:36:27 AM] [Pioneer AVR] Input [DVR/BDR] discovered (id: 15, type: 3). InitCount=5/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Add input n°2 - Name: DVR/BDR Id: 15 Type: 3 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : RGB170iPod/USB [2019-9-17 11:36:27 AM] [Pioneer AVR] Input [iPod/USB] discovered (id: 17, type: 9). InitCount=6/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Add input n°3 - Name: iPod/USB Id: 17 Type: 9 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:27 AM] [Pioneer AVR] Input does not exists. InitCount=7/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : RGB190HDMI 1 [2019-9-17 11:36:27 AM] [Pioneer AVR] Input [HDMI 1] discovered (id: 19, type: 3). InitCount=8/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Add input n°4 - Name: HDMI 1 Id: 19 Type: 3 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : RGB200HDMI 2 [2019-9-17 11:36:27 AM] [Pioneer AVR] Input [HDMI 2] discovered (id: 20, type: 3). InitCount=9/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Add input n°5 - Name: HDMI 2 Id: 20 Type: 3 [2019-9-17 11:36:27 AM] [Pioneer AVR] Receive data : RGB210HDMI 3 [2019-9-17 11:36:27 AM] [Pioneer AVR] Input [HDMI 3] discovered (id: 21, type: 3). InitCount=10/35 [2019-9-17 11:36:27 AM] [Pioneer AVR] Add input n°6 - Name: HDMI 3 Id: 21 Type: 3 [2019-9-17 11:36:28 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive data : RGB220HDMI 4 [2019-9-17 11:36:28 AM] [Pioneer AVR] Input [HDMI 4] discovered (id: 22, type: 3). InitCount=11/35 [2019-9-17 11:36:28 AM] [Pioneer AVR] Add input n°7 - Name: HDMI 4 Id: 22 Type: 3 [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive data : RGB230HDMI 5 [2019-9-17 11:36:28 AM] [Pioneer AVR] Input [HDMI 5] discovered (id: 23, type: 3). InitCount=12/35 [2019-9-17 11:36:28 AM] [Pioneer AVR] Add input n°8 - Name: HDMI 5 Id: 23 Type: 3 [2019-9-17 11:36:28 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive data : RGB240HDMI 6/MHL [2019-9-17 11:36:28 AM] [Pioneer AVR] Input [HDMI 6/MHL] discovered (id: 24, type: 3). InitCount=13/35 [2019-9-17 11:36:28 AM] [Pioneer AVR] Add input n°9 - Name: HDMI 6/MHL Id: 24 Type: 3 [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive data : RGB251APPLE TV
[2019-9-17 11:36:28 AM] [Pioneer AVR] Input [APPLE TV] discovered (id: 25, type: 3). InitCount=14/35 [2019-9-17 11:36:28 AM] [Pioneer AVR] Add input n°10 - Name: APPLE TV Id: 25 Type: 3 [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:28 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:28 AM] [Pioneer AVR] Input does not exists. InitCount=15/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:29 AM] [Pioneer AVR] Input does not exists. InitCount=16/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:29 AM] [Pioneer AVR] Input does not exists. InitCount=17/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : RGB330BT AUDIO [2019-9-17 11:36:29 AM] [Pioneer AVR] Input [BT AUDIO] discovered (id: 33, type: 0). InitCount=18/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Add input n°11 - Name: BT AUDIO Id: 33 Type: 0 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : RGB340HDMI 7 [2019-9-17 11:36:29 AM] [Pioneer AVR] Input [HDMI 7] discovered (id: 34, type: 3). InitCount=19/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Add input n°12 - Name: HDMI 7 Id: 34 Type: 3 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:29 AM] [Pioneer AVR] Input does not exists. InitCount=20/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : RGB380INTERNET RADIO [2019-9-17 11:36:29 AM] [Pioneer AVR] Input [INTERNET RADIO] discovered (id: 38, type: 2). InitCount=21/35 [2019-9-17 11:36:29 AM] [Pioneer AVR] Add input n°13 - Name: INTERNET RADIO Id: 38 Type: 2 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:29 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:29 AM] [Pioneer AVR] Input does not exists. InitCount=22/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : RGB410PANDORA [2019-9-17 11:36:30 AM] [Pioneer AVR] Input [PANDORA] discovered (id: 41, type: 0). InitCount=23/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Add input n°14 - Name: PANDORA Id: 41 Type: 0 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : RGB440MEDIA SERVER [2019-9-17 11:36:30 AM] [Pioneer AVR] Input [MEDIA SERVER] discovered (id: 44, type: 0). InitCount=24/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Add input n°15 - Name: MEDIA SERVER Id: 44 Type: 0 [2019-9-17 11:36:30 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : RGB450FAVORITES [2019-9-17 11:36:30 AM] [Pioneer AVR] Input [FAVORITES] discovered (id: 45, type: 0). InitCount=25/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Add input n°16 - Name: FAVORITES Id: 45 Type: 0 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:30 AM] [Pioneer AVR] Input does not exists. InitCount=26/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:30 AM] [Pioneer AVR] Input does not exists. InitCount=27/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : E06 [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive E06 error [2019-9-17 11:36:30 AM] [Pioneer AVR] Input does not exists. InitCount=28/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:30 AM] [Pioneer AVR] Receive data : RGB000PHONO [2019-9-17 11:36:30 AM] [Pioneer AVR] Input [PHONO] discovered (id: 00, type: 0). InitCount=29/35 [2019-9-17 11:36:30 AM] [Pioneer AVR] Add input n°17 - Name: PHONO Id: 00 Type: 0 [2019-9-17 11:36:31 AM] [Pioneer AVR] Receive data : RGB010CD [2019-9-17 11:36:31 AM] [Pioneer AVR] Input [CD] discovered (id: 01, type: 0). InitCount=30/35 [2019-9-17 11:36:31 AM] [Pioneer AVR] Add input n°18 - Name: CD Id: 01 Type: 0 [2019-9-17 11:36:31 AM] [Pioneer AVR] Receive data : RGB020TUNER [2019-9-17 11:36:31 AM] [Pioneer AVR] Input [TUNER] discovered (id: 02, type: 2). InitCount=31/35 [2019-9-17 11:36:31 AM] [Pioneer AVR] Add input n°19 - Name: TUNER Id: 02 Type: 2 [2019-9-17 11:36:31 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:31 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:32 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:32 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:32 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:32 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:33 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:33 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:34 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:34 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:35 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:35 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:36 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:36 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:37 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:37 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:37 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:37 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:38 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:38 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:39 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:39 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:40 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:40 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:41 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:41 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:42 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:42 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:42 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:42 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:43 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:43 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:44 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:44 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:45 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:45 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:46 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:46 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:47 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:47 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:47 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:47 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:48 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:48 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:49 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:50 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:50 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:51 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:51 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:52 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:52 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:52 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:52 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:53 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:53 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:54 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:54 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:55 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:55 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:56 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:56 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:57 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:57 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:36:57 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:36:57 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:58 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:58 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready ^C[2019-9-17 11:36:58 AM] Got SIGINT, shutting down Homebridge... [2019-9-17 11:36:59 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:36:59 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:00 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:00 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:01 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:01 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:02 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:02 AM] [Music] applescript: set lib to (load script "/usr/local/lib/node_modules/homebridge-music/scripts/iTunes.scpt") tell lib to getState() [2019-9-17 11:37:02 AM] [Music] applescript result: {"on":false,"volume":40,"track":"","speakers":{"Computer":{"on":false,"volume":27},"AirPort Express old di Alessandro":{"on":false,"volume":39},"AirPort Express di Mamma":{"on":false,"volume":39},"AS-AFTT[AirPlay]":{"on":false,"volume":33},"Matrimoniale":{"on":false,"volume":39},"Matteo Apple TV":{"on":false,"volume":33},"Salone":{"on":false,"volume":39},"STEREO ":{"on":false,"volume":39}}} [2019-9-17 11:37:02 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:03 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready [2019-9-17 11:37:03 AM] [Pioneer AVR] Waiting for pioneerAvrAccessory to be ready Mini-di-User:~ user$

[mark125431]

Hi

I’m trying this plugin out on my VSX -831. And I get an ECONNREFUSED error.

Any suggestions please ?

[Moskvitch]

Same problem here. Homebridge hangs Pioneer SC-2024, Raspberry 4 ?RGB05 returns RGB050TV

[Moskvitch]

[10/2/2019, 12:28:16 AM] Loading 1 accessories... [10/2/2019, 12:28:16 AM] [Bedroom AVR] Initializing pioneerAvrAccessory accessory... [10/2/2019, 12:28:16 AM] [Bedroom AVR] Discovering inputs [10/2/2019, 12:28:16 AM] [Bedroom AVR] Web Interface enabled [10/2/2019, 12:28:16 AM] [Bedroom AVR] Add input n°0 - Name: MULTI CH IN Id: 12 Type: 0 [10/2/2019, 12:28:16 AM] [Bedroom AVR] Add input n°1 - Name: USB-DAC Id: 13 Type: 0 [10/2/2019, 12:28:16 AM] [Bedroom AVR] Add input n°2 - Name: DVR/BDR Id: 15 Type: 3 [10/2/2019, 12:28:17 AM] [Bedroom AVR] Add input n°3 - Name: iPod/USB Id: 17 Type: 9 [10/2/2019, 12:28:17 AM] [Bedroom AVR] Add input n°4 - Name: HDMI 1 Id: 19 Type: 3 [10/2/2019, 12:28:17 AM] [Bedroom AVR] Add input n°5 - Name: HDMI 2 Id: 20 Type: 3 [10/2/2019, 12:28:17 AM] [Bedroom AVR] Add input n°6 - Name: HDMI 3 Id: 21 Type: 3 [10/2/2019, 12:28:17 AM] [Bedroom AVR] Add input n°7 - Name: HDMI 4 Id: 22 Type: 3 [10/2/2019, 12:28:18 AM] [Bedroom AVR] Add input n°8 - Name: HDMI 5 Id: 23 Type: 3 [10/2/2019, 12:28:18 AM] [Bedroom AVR] Add input n°9 - Name: HDMI 6/MHL Id: 24 Type: 3 [10/2/2019, 12:28:18 AM] [Bedroom AVR] Add input n°10 - Name: Oppo Id: 25 Type: 3 [10/2/2019, 12:28:19 AM] [Bedroom AVR] Add input n°11 - Name: BT AUDIO Id: 33 Type: 0 [10/2/2019, 12:28:19 AM] [Config] Homebridge Config UI X v4.6.1 is listening on :: port 8080 [10/2/2019, 12:28:19 AM] [Bedroom AVR] Add input n°12 - Name: HDMI 7 Id: 34 Type: 3 [10/2/2019, 12:28:19 AM] [Bedroom AVR] Add input n°13 - Name: INTERNET RADIO Id: 38 Type: 2 [10/2/2019, 12:28:19 AM] [Bedroom AVR] Add input n°14 - Name: PANDORA Id: 41 Type: 0 [10/2/2019, 12:28:20 AM] [Bedroom AVR] Add input n°15 - Name: MEDIA SERVER Id: 44 Type: 0 [10/2/2019, 12:28:20 AM] [Bedroom AVR] Add input n°16 - Name: FAVORITES Id: 45 Type: 0 [10/2/2019, 12:28:20 AM] [Bedroom AVR] Add input n°17 - Name: Spotify Id: 57 Type: 0 [10/2/2019, 12:28:20 AM] [Bedroom AVR] Add input n°18 - Name: PHONO Id: 00 Type: 0 [10/2/2019, 12:28:21 AM] [Bedroom AVR] Add input n°19 - Name: CD Id: 01 Type: 0 [10/2/2019, 12:28:21 AM] [Bedroom AVR] Add input n°20 - Name: TUNER Id: 02 Type: 2

[mark125431]

Mine doesn’t get as far as yours. Mine hangs at the “discovering inputs”

Any ideas pls ?

IControlAV5 works perfectly and other plugins are working on my homebridge

[steeeeev0]

I am having a similar issue. I have a clean install of node, npm, and homebridge—-and this is the only plugin installed.

Here are the contents of config.json:

{ "bridge": { "name": "Homebridge-2", "username": "CC:22:3D:E3:CE:32", "port": 51826, "pin": "031-28-111" },

"description": "Homebridge-2.", "ports": { "start": 52100, "end": 52150, "comment": "This section is used to control the range of ports that separate accessory (like camera or television) should be bind to." }, "accessories": [ { "accessory": "pioneerAvrAccessory", "model": "SX-S30", "name": "Roundhouse AVR", "description": "AV Receiver", "host": "10.0.1.85", "port": 23 } ] }

Here are the results of running homebridge (as a service):

Dec 04 03:55:26 HOMEBRIDGE2 systemd[1]: Started Node.js HomeKit Server. Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] Loaded config.json with 1 accessories and 0 platforms. Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] --- Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] Loaded plugin: homebridge-pioneer-avr Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] Registering accessory 'homebridge-pioneer-avr.pioneerAvrAccessory' Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] --- Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] Loading 1 accessories... Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] [Roundhouse AVR] Initializing pioneerAvrAccessory accessory... Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] TypeError [ERR_INVALID_ARG_TYPE]: The "path" argument must be of type string. Received type undefined Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at validateString (internal/validators.js:112:11) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at join (path.js:1040:7) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at module.exports (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/persist-path/index.js:19:22) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at new pioneerAvrAccessory (/usr/local/lib/node_modules/homebridge-pioneer-avr/index.js:25:40) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at Server._loadAccessories (/usr/local/lib/node_modules/homebridge/lib/server.js:308:29) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at Server.run (/usr/local/lib/node_modules/homebridge/lib/server.js:91:38) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at module.exports (/usr/local/lib/node_modules/homebridge/lib/cli.js:59:10) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at Object. (/usr/local/lib/node_modules/homebridge/bin/homebridge:17:22) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at Module._compile (internal/modules/cjs/loader.js:959:30) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:995:10) Dec 04 03:55:27 HOMEBRIDGE2 homebridge[19593]: [12/4/2019, 3:55:27 AM] Got SIGTERM, shutting down Homebridge... Dec 04 03:55:32 HOMEBRIDGE2 systemd[1]: homebridge.service: Main process exited, code=exited, status=143/n/a Dec 04 03:55:32 HOMEBRIDGE2 systemd[1]: homebridge.service: Failed with result 'exit-code'.

Are there any updates to this issue?

[kazcangi]

@mark125431 Hello, some of receivers use port 8102 instead of 23. Can you try this port in the config file ?

[mark125431]

Hi

I’ve tried that port and still not working 😥

Sent from my iPhone

On 11 Dec 2019, at 19:06, Kazcangi notifications@github.com wrote:

 @mark125431 Hello, some of receivers use port 8102 instead of 23. Can you try this port in the config file ?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub, or unsubscribe.

[steeeeev0]

I also tried both ports, to no avail.

[kazcangi]

Sometimes, while testing plugin, I have to unplug my pioneer for 10 second then plug back again to unlock telnet port.

[kazcangi]

I have published a new version 0.8.1. It may work better. But not for all I think

[steeeeev0]

I will give that a try soon. Are you aware of any commands that I could do to check to see if the telnet port is available/responding?

[steeeeev0]

I did a port scan on my Pioneer SX-S30. Ports 23 and 8102 were not open... these are the ports I found (the description is probably alluding to what common uses of each port are, not necessarily what the receiver is using them for)

80 - http; 4545 - worldscores; 7000 - afs3-fileserver; 8008 - http-alt; 8009 - nvme-disc; 8080 - http-alt.

I tried adjusting the config.json in homebridge to try each of these ports, but got the same fail error. Hoping maybe this list of ports will help give clues on what else to try.

[steeeeev0]

I found additional ports open:

8443 - pcsync-https; 8554 - rtsp-alt; 9000 - cslistener; 9013 - unknown; 10001 - scp-config; 10109 - unknown; 10110 - nmea-0183; 10111 - unknown; 10113 - netiq-endpoint; 10120 - unknown.

Tried each of these ports in the config.json as well, but the same result - no go at this point.

[mark125431]

Worth noting that The IControlAV5 app uses port 60128. I have tried the above also with no luck

[kazcangi]

In configuration menu, is "Network Standby" enabled ? You can find it in "System Setup" -> "Network Setup" -> Network standby

[mark125431]

Hey. So. I get an error connection refused on ports 23, 8102 However if I try port 60128. It connects but hangs on discovering inputs My pioneer model is VSX-831

[mark125431]

This is the error I get

I get this on both port 23 and port 8102,

Any advise please?

[1/1/2020, 4:48:54 PM] [My Pioneer AVR] { Error: connect ECONNREFUSED 192.168.0.10:8102 at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1106:14) at Function.module.exports.loopWhile (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:71:23) at Function.sleep (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:41:18) at pioneerAvrAccessory.getServices (/usr/local/lib/node_modules/homebridge-pioneer-avr/index.js:317:28) at Server._createAccessory (/usr/local/lib/node_modules/homebridge/lib/server.js:427:36) at Server._loadAccessories (/usr/local/lib/node_modules/homebridge/lib/server.js:309:26) at Server.run (/usr/local/lib/node_modules/homebridge/lib/server.js:91:38) at module.exports (/usr/local/lib/node_modules/homebridge/lib/cli.js:59:10) at Object. (/usr/local/lib/node_modules/homebridge/bin/homebridge:17:22) at Module._compile (internal/modules/cjs/loader.js:776:30) errno: 'ECONNREFUSED', code: 'ECONNREFUSED', syscall: 'connect', address: '192.168.0.10', port: 8102 } (node:5536) UnhandledPromiseRejectionWarning: ReferenceError: data is not defined at PioneerAvr.sendCommand (/usr/local/lib/node_modules/homebridge-pioneer-avr/pioneer-avr.js:265:5) at process._tickCallback (internal/process/next_tick.js:68:7) at Function.module.exports.loopWhile (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:70:11) at Function.sleep (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:41:18) at Socket.socket.on (/usr/local/lib/node_modules/homebridge-pioneer-avr/telnet-avr.js:34:25) at Socket.emit (events.js:198:13) at TCP._handle.close (net.js:606:12) at Function.module.exports.loopWhile (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:71:23) at Function.sleep (/usr/local/lib/node_modules/homebridge-pioneer-avr/node_modules/deasync/index.js:41:18) at pioneerAvrAccessory.getServices (/usr/local/lib/node_modules/homebridge-pioneer-avr/index.js:317:28) (node:5536) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 35) hoobs : TTY=pts/0 ; PWD=/var/lib/homebridge ; USER=root ; COMMAND=/bin/journalctl -o cat -n 500 -f -u homebridge -u homebridge-config-ui-x pam_unix(sudo:session): session opened for user root by (uid=0) hoobs : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -9 5565 pam_unix(sudo:session): session opened for user root by (uid=0) pam_unix(sudo:session): session closed for user root hoobs : TTY=pts/0 ; PWD=/var/lib/homebridge ; USER=root ; COMMAND=/bin/journalctl -o cat -n 500 -f -u homebridge -u homebridge-config-ui-x pam_unix(sudo:session): session opened for user root by (uid=0) [1/1/2020, 4:49:47 PM] [Config] [HapClient] Discovery :: Ended

[kazcangi]

Hi

The main error is connection refused by your AVR. The telnet server of the AVR accept only one connection at a time. If something went wrong with it (like a older connection), the port is blocked.

The only way to recover it is to power cycle the AVR by unplug the power cord, wait 10 seconds, then plug it back.

AVControl5 app use port 8102. If your AVR have the port 23 opened, you should use it. If the port 23 is down, you have to use 8102.

[mark125431]

Hi I don’t have an open port of 23 or 8102 The ports open I have are 80 5000 8009 8080 8443 8888

My icontrolAV5 communicates on port 60128 but if I use this port in the config it just hangs at discovering inputs and homebridge doesn’t run

If I open a telnet session on port 60128 I can see the comms between the AVR and the app however copying the code doesn’t appear to control the AVR 😟 Any other ideas I could try ?

[macjbraun]

My instance was hanging at the same place as xzi above. After input 19. I updated the pioneer-avr.js file according to your instructions. I was receiving a number of E06 errors. I went back in and commented out those inputs that a response was not received from. I have a VSX830. I received 15 E06 errors back from the Avr. Everything is working now. Perhaps you need to look at your error routine.

[amaisano]

The repeating "[Pioneer AVR] Waiting for pioneerAvrAccessory to be ready" message prevents home bridge from properly starting - blocking all other devices. I agree with @macjbraun that this error routine should be tweaked so that it doesn't block loading of home bridge.