[SOLVED] Trying to get started with InsteonPLM

I hope I have included all of the requested info. I would like to understand the error logs better. I would appreciate any help possible.

  • Platform information:
    • Hardware: CPUArchitecture/RAM/storage
      i7 3770k 16GB RAM 238 GB Storage
    • OS: what OS is used and which version
      Windows 10 Pro 64-bit
    • Java Runtime Environment: which java platform is used and what version
      1_8_0_191 x86/64
    • openHAB version:
      2.4.0
  • Issue of the topic: please be detailed explaining your issue
    I am receiving error messages, which I cannot decypher. It apparently loads ok until it gets to InsteonPLM, at which point, it loads ok for a few items, then errors out.
  • Please post configurations (if applicable):
    • Items configuration related to the issue
      Dimmer OfficeLight “Office light” (house, lights) [“Lighting”] {insteonplm=“29.a5.d6:F00.00.13#switch”}

    • Sitemap configuration related to the issue
      sitemap house label=“house” {
      Frame label=“house”
      {
      Switch item=OfficeLight label=“OFFICE Light”
      // insteonplm=“29.a5.d6:F00.00.13#switch”
      }
      }

    • Rules code related to the issue
      None

    • Services configuration related to the issue

  • If logs where generated please post these here using code fences:

2019-02-02 14:09:59.344 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2019-02-02 14:09:59.592 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to ‘America/Denver’.
2019-02-02 14:09:59.594 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ‘5.878332109674304,0.000000000000008366213032226278’.
2019-02-02 14:09:59.595 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to ‘en_US’.
2019-02-02 14:10:01.085 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080)
2019-02-02 14:10:01.551 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.items’
2019-02-02 14:10:02.247 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.rules’
2019-02-02 14:10:02.254 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model ‘house.rules’ is either empty or cannot be parsed correctly!
2019-02-02 14:10:02.294 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2019-02-02 14:10:02.420 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.sitemap’
2019-02-02 14:10:02.490 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.things’
2019-02-02 14:10:02.799 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.0.31:8080
2019-02-02 14:10:02.895 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.0.31:8443
2019-02-02 14:10:02.969 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2019-02-02 14:10:03.014 [INFO ] [nding.insteonplm.InsteonPLMActivator] - Insteon PLM binding has been started.
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - refresh interval set to 60s
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - poll interval set to 300000 per config file
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - dead device timeout set to 3000s
2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: poll_interval -> 300000
2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: port_0 -> /hub2/xxx@test.com;xxxxxx@192.168.0.216:25105,poll_time=1000
2019-02-02 14:10:03.050 [INFO ] [b.core.service.AbstractActiveService] - InsteonPLM has been started
2019-02-02 14:10:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0
2019-02-02 14:10:03.055 [ERROR] [org.apache.felix.configadmin ] - [org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService, id=362, bundle=340/mvn:org.openhab.binding/org.openhab.binding.insteonplm/1.13.0]: Unexpected problem updating configuration org.openhab.insteonplm
java.lang.ArrayIndexOutOfBoundsException: 1
at org.openhab.binding.insteonplm.internal.driver.IOStream.makeHub2014Stream(IOStream.java:142) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.IOStream.s_create(IOStream.java:120) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.Port.(Port.java:89) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.Driver.addPort(Driver.java:80) ~[?:?]
at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.initialize(InsteonPLMActiveBinding.java:372) ~[?:?]
at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.updated(InsteonPLMActiveBinding.java:276) ~[?:?]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updated(ManagedServiceTracker.java:189) ~[9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updateService(ManagedServiceTracker.java:152) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.provideConfiguration(ManagedServiceTracker.java:85) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.provide(ConfigurationManager.java:1102) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.run(ConfigurationManager.java:1058) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.UpdateThread.run0(UpdateThread.java:138) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:105) [9:org.apache.felix.configadmin:1.9.4]
at java.lang.Thread.run(Unknown Source) [?:?]
2019-02-02 14:11:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0

please edit your post and use code blocks

Ok, I would be glad to do so, but what are code blocks? Please excuse my ignorance.

Ok, I do not know where my “code blocks” on the error logs went, as I put them there, but they disappeared.

I hope I have included all of the requested info. I would like to understand the error logs better. I would appreciate any help possible.

<Platform information:>
    Hardware: CPUArchitecture/RAM/storage
    i7 3770k 16GB RAM 238 GB Storage
    OS: what OS is used and which version
    Windows 10 Pro 64-bit
    Java Runtime Environment: which java platform is used and what version
    1_8_0_191 x86/64
    openHAB version:
    2.4.0
<Platform information:>

I am receiving error messages, which I cannot decypher. It apparently loads ok until it gets to InsteonPLM, at which point, it loads ok for a few items, then errors out.


Please post configurations (if applicable):


```Confirguration

Items configuration related to the issue
Dimmer OfficeLight “Office light” (house, lights) [“Lighting”] {insteonplm=“29.a5.d6:F00.00.13#switch”}


Sitemap configuration related to the issue:

sitemap house label=“house” { Frame label=“house” { Switch item=OfficeLight label=“OFFICE Light” // insteonplm=“29.a5.d6:F00.00.13#switch” } }


    Rules code related to the issue
    None

    Services configuration related to the issue
If logs where generated please post these here using code fences:
> 
> Error Logs:
2019-02-02 14:09:59.344 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder 2019-02-02 14:09:59.592 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to ‘America/Denver’. 2019-02-02 14:09:59.594 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ‘5.878332109674304,0.000000000000008366213032226278’. 2019-02-02 14:09:59.595 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to ‘en_US’. 2019-02-02 14:10:01.085 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080) 2019-02-02 14:10:01.551 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.items’ 2019-02-02 14:10:02.247 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.rules’ 2019-02-02 14:10:02.254 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model ‘house.rules’ is either empty or cannot be parsed correctly! 2019-02-02 14:10:02.294 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007 2019-02-02 14:10:02.420 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.sitemap’ 2019-02-02 14:10:02.490 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.things’ 2019-02-02 14:10:02.799 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.0.31:8080 2019-02-02 14:10:02.895 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.0.31:8443 2019-02-02 14:10:02.969 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui 2019-02-02 14:10:03.014 [INFO ] [nding.insteonplm.InsteonPLMActivator] - Insteon PLM binding has been started. 2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - refresh interval set to 60s 2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - poll interval set to 300000 per config file 2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - dead device timeout set to 3000s 2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: poll_interval -&gt; 300000 2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: port_0 -&gt; /hub2/xxx@test.com;xxxxxx@192.168.0.216:25105,poll_time=1000 2019-02-02 14:10:03.050 [INFO ] [b.core.service.AbstractActiveService] - InsteonPLM has been started 2019-02-02 14:10:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0 2019-02-02 14:10:03.055 [ERROR] [org.apache.felix.configadmin ] - [org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService, id=362, bundle=340/mvn:org.openhab.binding/org.openhab.binding.insteonplm/1.13.0]: Unexpected problem updating configuration org.openhab.insteonplm java.lang.ArrayIndexOutOfBoundsException: 1 at org.openhab.binding.insteonplm.internal.driver.IOStream.makeHub2014Stream(IOStream.java:142) ~[?:?] at org.openhab.binding.insteonplm.internal.driver.IOStream.s_create(IOStream.java:120) ~[?:?] at org.openhab.binding.insteonplm.internal.driver.Port.(Port.java:89) ~[?:?] at org.openhab.binding.insteonplm.internal.driver.Driver.addPort(Driver.java:80) ~[?:?] at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.initialize(InsteonPLMActiveBinding.java:372) ~[?:?] at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.updated(InsteonPLMActiveBinding.java:276) ~[?:?] at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updated(ManagedServiceTracker.java:189) ~[9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updateService(ManagedServiceTracker.java:152) [9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.helper.ManagedServiceTracker.provideConfiguration(ManagedServiceTracker.java:85) [9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.provide(ConfigurationManager.java:1102) [9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.run(ConfigurationManager.java:1058) [9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.UpdateThread.run0(UpdateThread.java:138) [9:org.apache.felix.configadmin:1.9.4] at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:105) [9:org.apache.felix.configadmin:1.9.4] at java.lang.Thread.run(Unknown Source) [?:?] 2019-02-02 14:11:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0

Ok, I will try again. I reread the post about code blocks, and I interpretted it differently from the first time.

I hope I have included all of the requested info. I would like to understand the error logs better. I would appreciate any help possible.

    Platform information:
        Hardware: CPUArchitecture/RAM/storage
        i7 3770k 16GB RAM 238 GB Storage
        OS: what OS is used and which version
        Windows 10 Pro 64-bit
        Java Runtime Environment: which java platform is used and what version
        1_8_0_191 x86/64
        openHAB version:
        2.4.0
    Issue of the topic: please be detailed explaining your issue
    I am receiving error messages, which I cannot decypher. It apparently loads ok until it gets to InsteonPLM, at which point, it loads ok for a few items, then errors out.
    Please post configurations (if applicable):

        Items configuration related to the issue
        Dimmer OfficeLight “Office light” (house, lights) [“Lighting”] {insteonplm=“29.a5.d6:F00.00.13#switch”}

        Sitemap configuration related to the issue
        sitemap house label=“house” {
        Frame label=“house”
        {
        Switch item=OfficeLight label=“OFFICE Light”
        // insteonplm=“29.a5.d6:F00.00.13#switch”
        }
        }

        Rules code related to the issue
        None

        Services configuration related to the issue

    If logs where generated please post these here using code fences:

2019-02-02 14:09:59.344 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2019-02-02 14:09:59.592 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to ‘America/Denver’.
2019-02-02 14:09:59.594 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ‘5.878332109674304,0.000000000000008366213032226278’.
2019-02-02 14:09:59.595 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to ‘en_US’.
2019-02-02 14:10:01.085 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080)
2019-02-02 14:10:01.551 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.items’
2019-02-02 14:10:02.247 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.rules’
2019-02-02 14:10:02.254 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model ‘house.rules’ is either empty or cannot be parsed correctly!
2019-02-02 14:10:02.294 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2019-02-02 14:10:02.420 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.sitemap’
2019-02-02 14:10:02.490 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model ‘house.things’
2019-02-02 14:10:02.799 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.0.31:8080
2019-02-02 14:10:02.895 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.0.31:8443
2019-02-02 14:10:02.969 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2019-02-02 14:10:03.014 [INFO ] [nding.insteonplm.InsteonPLMActivator] - Insteon PLM binding has been started.
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - refresh interval set to 60s
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - poll interval set to 300000 per config file
2019-02-02 14:10:03.049 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - dead device timeout set to 3000s
2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: poll_interval -> 300000
2019-02-02 14:10:03.050 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - config: port_0 -> /hub2/xxx@test.com;xxxxxx@192.168.0.216:25105,poll_time=1000
2019-02-02 14:10:03.050 [INFO ] [b.core.service.AbstractActiveService] - InsteonPLM has been started
2019-02-02 14:10:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0
2019-02-02 14:10:03.055 [ERROR] [org.apache.felix.configadmin ] - [org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService, id=362, bundle=340/mvn:org.openhab.binding/org.openhab.binding.insteonplm/1.13.0]: Unexpected problem updating configuration org.openhab.insteonplm
java.lang.ArrayIndexOutOfBoundsException: 1
at org.openhab.binding.insteonplm.internal.driver.IOStream.makeHub2014Stream(IOStream.java:142) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.IOStream.s_create(IOStream.java:120) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.Port.(Port.java:89) ~[?:?]
at org.openhab.binding.insteonplm.internal.driver.Driver.addPort(Driver.java:80) ~[?:?]
at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.initialize(InsteonPLMActiveBinding.java:372) ~[?:?]
at org.openhab.binding.insteonplm.InsteonPLMActiveBinding.updated(InsteonPLMActiveBinding.java:276) ~[?:?]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updated(ManagedServiceTracker.java:189) ~[9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.updateService(ManagedServiceTracker.java:152) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.helper.ManagedServiceTracker.provideConfiguration(ManagedServiceTracker.java:85) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.provide(ConfigurationManager.java:1102) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.ConfigurationManager$ManagedServiceUpdate.run(ConfigurationManager.java:1058) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.UpdateThread.run0(UpdateThread.java:138) [9:org.apache.felix.configadmin:1.9.4]
at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:105) [9:org.apache.felix.configadmin:1.9.4]
at java.lang.Thread.run(Unknown Source) [?:?]
2019-02-02 14:11:03.053 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - devices: 0 configured, 0 polling, msgs received: 0

Trying again, on code blocks.

Ok, I think I understand the code blocks. Please re-read the
topic, past the first post, and let me know if I have some idea of
what to do.

BW, thanks for pointing out the error of my ways.

Now, I have code blocks more or less correct, can anybody help me with the error codes above?

Can you post your configuration for insteonPlm? I have a feeling you are using incorrect port.

I believe this is the configuration for my InsteonPLM:

# The insteon PLM controller port, one for each modem or hub.
# You can have multiple ports, but that has never been tested, use at your own peril.
#
# examples of valid port configurations for serial or usb modems:
#
# port_0=/dev/insteon  (Linux, with serial port symlinked to /dev/insteon)
# port_0=/dev/ttyS0    (Linux, with plain old serial modem)
# port_0=/dev/ttyUSB0  (Linux, with usb based PLM modem)
# port_0=COM1	  (Windows, with serial/usb modem on COM1)

#
# to connect to an Insteon Hub2 (the 2014 version) on port 25105, with
# a poll interval of 1000ms = 1sec:
#
port_0=/hub2/user/pwd@192.168.0.207:25105,poll_time=1000

#
# to connect to the raw tcp feed on an older Insteon Hub (pre 2014 version) on port 9761
#
# port_0=/hub/localhost:9761

# Poll interval (optional, in milliseconds, defaults to 300000).
# Poll too often and you will overload the insteon network,
# leading to sluggish or no response when trying to send messages to devices.
# The default poll interval of 300s has been tested and found to be a good
# compromise in a configuration of about 110 switches/dimmers.
#
poll_interval=300000

# Refresh value (optional, in milliseconds, defaults to 600000)
# The refresh interval is not critical, since only device statistics are logged
# upon refresh (the polling operates under different timers).
#
refresh=600000

# optional file with additional device types. The syntax of
# the file is identical to the device_types.xml file in the
# source tree. Please remember to post successfully added
# device types to the openhab group so the developers
# can include them into the device_types.xml file!

#more_devices=/path_to_file/more_devices.xml

# optional file with additional feature templates, like
# in the device_features.xml file in the source tree.

#more_features=/path_to_file/more_features.xml

Since it’s a raw tcp, have you tried connecting to it via putty, telnet, or netcat? Just want to make sure the tcp is live.

Which port do I use? I use putty, but I am unsure which port to use. I seemed to have read it somewhere, but I do not remember where.

port_0=/hub2/user/pwd@192.168.0.207:25105,poll_time=1000
---------------IP------^ ----PORT --^

No, I cannot connect there. That is my Insteon Hub. Is that what you want me to try?

It just times out.

Then that’s the issue. If you are timing out just connecting to it, then OH will not be able to connect also.

Ok. Thanks. Do you have any suggestions?

Ok, thanks. You have clued me in on the correts item. I did not have my Insteon Hub set up correctly. Now, maybe I can get started with OpenHab2. Many thanks!!!

you can tick it as the accepted solution :wink:

Great, now I can control one of my lights, so, I am on the way.