Just updated my previously working configuration of OH2 to the latest SNAPSHOT from last night (Dec 5th 2016).
Now the insteonPLM binding downloads the modem database, but aborts slightly into assigning items to the devices found in the database.
This is the log:
2016-12-05 09:53:28.699 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - got msg: IN:Cmd:0x57|RecordFlags:0xE2|ALLLinkGroup:0x01|LinkAddr:32.A4.D4|LinkData1:0x01|LinkData2:0x35|LinkData3:0x48|
2016-12-05 09:53:28.699 [DEBUG] [ding.insteonplm.internal.driver.Port] - writing (0): OUT:Cmd:0x6A|
2016-12-05 09:53:28.750 [DEBUG] [onplm.internal.device.ModemDBBuilder] - got all link records.
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB ------- start of modem link records ------------------
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 1F.7C.03: CTRL group: 00 data1: 01 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 1F.7C.03: RESP group: 01 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB -----
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 26.41.54: RESP group: 02 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 26.41.54: RESP group: 03 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 26.41.54: RESP group: 04 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 26.41.54: RESP group: 01 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 26.41.54: CTRL group: 00 data1: 03 data2: 10 data3: 10
2016-12-05 09:53:28.751 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB -----
...
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.82.76: CTRL group: 00 data1: 01 data2: 00 data3: 00
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.82.76: RESP group: 01 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB -----
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.DA.D6: CTRL group: 01 data1: 01 data2: 00 data3: 00
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.DA.D6: CTRL group: 00 data1: 01 data2: 00 data3: 00
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.DA.D6: RESP group: 01 data1: 00 data2: 00 data3: 00
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.DA.D6: CTRL group: 27 data1: 01 data2: 20 data3: 20
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB 38.DA.D6: CTRL group: 0F data1: 01 data2: 20 data3: 20
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB -----
2016-12-05 09:53:28.755 [DEBUG] [onplm.internal.device.ModemDBBuilder] - MDB ---------------- end of modem link records -----------
2016-12-05 09:53:28.755 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - modem database has 49 entries!
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 1F.7C.03
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 26.41.54
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 3D.4A.3A
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.85.F6
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.4D.5C
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.C0.8A
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 26.5A.BA
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 37.24.16
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 33.20.55
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 36.B4.0C
2016-12-05 09:53:28.755 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 28.43.36
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 11.99.C9
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.19.66
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.BB.80
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.D9.20
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 20.AF.0B
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.A5.AE
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.A4.D4
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.4D.74
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2E.87.56
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 27.94.5A
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2E.87.97
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2F.73.A5
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 3C.D6.4D
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 25.33.A3
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 34.CB.62
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.4D.65
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.CB.DA
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2C.9E.9A
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 22.17.16
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.4D.0E
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2E.9C.E5
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.C0.1E
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.7E.93
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 2F.74.A6
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.E7.A3
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 27.4E.7B
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 31.A7.49
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 27.4A.41
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.8C.35
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 3B.AE.82
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 32.BB.7F
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 15.0C.D2
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 13.CA.8E
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.80.F5
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 28.5C.D7
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 1F.C9.FD
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.82.76
2016-12-05 09:53:28.756 [DEBUG] [g.insteonplm.InsteonPLMActiveBinding] - modem db entry: 38.DA.D6
2016-12-05 09:53:28.756 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 26.41.54 found in the modem database and the modem controls groups [0x00] and responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.757 [DEBUG] [ng.insteonplm.internal.driver.Poller] - start polling device 26.41.54|buttonD->RemoteButton4(0:0:3)|buttonB->RemoteButton2(1:0:3)|buttonC->RemoteButton3(1:0:3)|lastheardfrom->GenericLastTime(0:0:0)|buttonA->RemoteButton1(1:0:3)
2016-12-05 09:53:28.757 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2E.87.56 found in the modem database and the modem responds to groups [0x01,0x02,0x03,0x04,0x04].
2016-12-05 09:53:28.757 [DEBUG] [ng.insteonplm.internal.driver.Poller] - start polling device 2E.87.56|buttonD->RemoteButton4(1:0:3)|buttonB->RemoteButton2(1:0:3)|buttonC->RemoteButton3(1:0:3)|lastheardfrom->GenericLastTime(0:0:0)|buttonA->RemoteButton1(1:0:3)
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2E.87.97 found in the modem database and the modem responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.761 [DEBUG] [ng.insteonplm.internal.driver.Poller] - start polling device 2E.87.97|buttonD->RemoteButton4(1:0:3)|buttonB->RemoteButton2(1:0:3)|buttonC->RemoteButton3(1:0:3)|lastheardfrom->GenericLastTime(0:0:0)|buttonA->RemoteButton1(1:0:3)
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 1F.7C.03 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 3D.4A.3A found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.85.F6 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x0F] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.4D.5C found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.C0.8A found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 26.5A.BA found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 37.24.16 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 33.20.55 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x00,0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 36.B4.0C found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x04].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 28.43.36 found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 11.99.C9 found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x03,0x04,0x05,0x06].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.19.66 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.BB.80 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.D9.20 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x1B] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 20.AF.0B found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.761 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.A5.AE found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x00] and responds to groups [0x01,0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.A4.D4 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x01] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.4D.74 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 27.94.5A found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x00] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2F.73.A5 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x22,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 3C.D6.4D found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 25.33.A3 found in the modem database, but is not configured as an item and the modem controls groups [0x00].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 34.CB.62 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x00] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.4D.65 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x0F,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.CB.DA found in the modem database, but is not configured as an item and the modem controls groups [0x00].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2C.9E.9A found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 22.17.16 found in the modem database, but is not configured as an item and the modem controls groups [0xF2,0xF3,0xF4,0xF5,0xF6,0xF7,0xF8] and responds to groups [0x01,0x03,0x04,0x05,0x06].
2016-12-05 09:53:28.762 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.4D.0E found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2E.9C.E5 found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.C0.1E found in the modem database, but is not configured as an item and the modem controls groups [0xF2,0xF3,0xF4,0xF5,0xF6,0xF7,0xF8,0xF8,0x27] and responds to groups [0x01,0x03,0x04,0x05,0x06].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.7E.93 found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 2F.74.A6 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x10] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.E7.A3 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01,0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 27.4E.7B found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 31.A7.49 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 27.4A.41 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.8C.35 found in the modem database, but is not configured as an item and the modem responds to groups [0x01,0x02,0x03,0x04].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 3B.AE.82 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 32.BB.7F found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 15.0C.D2 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 13.CA.8E found in the modem database, but is not configured as an item and the modem controls groups [0x00].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.80.F5 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x1B] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 28.5C.D7 found in the modem database, but is not configured as an item and the modem controls groups [0x01] and responds to groups [0x01,0x02,0x03].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 1F.C9.FD found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x27] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.82.76 found in the modem database, but is not configured as an item and the modem controls groups [0x00] and responds to groups [0x01].
2016-12-05 09:53:28.763 [INFO ] [g.insteonplm.InsteonPLMActiveBinding] - device 38.DA.D6 found in the modem database, but is not configured as an item and the modem controls groups [0x00,0x01,0x0F,0x27] and responds to groups [0x01].
My last update was about a week ago, so something has changed in the last week. Took me a while to get everything working after the update last night, so I only noticed that insteon wasn’t working this morning.
Anyone know why this is happening?