Re: [mh] Insteon I2 Support

SourceForge Headquarters 225 Broadway Suite 1600 San Diego, CA 92101 +1 (858) 454-5900

On Sun, Feb 17, 2013 at 02:40:46PM -0800, Marc MERLIN wrote:
> So, I tried scan all with your just updated code, and aborted with no
> message again.
> 
> 17/02/2013 14:31:49  [Insteon_PLM] DEBUG3: Saving parsed data fragment: 025010948818d4ce
> 17/02/2013 14:31:49  [Insteon_PLM] DEBUG3: Prepending prior data fragment: 025010948818d4ce
> 17/02/2013 14:31:49  [Insteon_PLM] DEBUG3: Received PLM raw data: 025010948818d4ce262f00
> 17/02/2013 14:31:49  [Insteon_PLM] DEBUG4:
>          PLM Command: (0250) insteon_received
>             From Address: 10:94:88
>               To Address: 18:d4:ce
>            Message Flags: 26
>                 Message Type: (001) ACK of Direct Message
>               Message Length: (0) Standard Length
>                    Hops Left: 1
>                     Max Hops: 2
>          Insteon Message: 2f00
>                        Cmd 1: (2f) Light OFF at Ramp Rate
>                        Cmd 2: (00) Ramp Rate
> 
> 17/02/2013 14:31:49  [Insteon::BaseInterface] DEBUG2: Message received with 1 hops left, delaying next transmit to avoid collisions with remaining hops.
> 17/02/2013 14:31:49  [Insteon::BaseInterface] DEBUG3: PLM command:insteon_received; Device command:read_write_aldb; type:direct; group:
> 17/02/2013 14:31:49  [Insteon::ALDB_i2] DEBUG3: $fmr_outside [0x0fd7] received: 00 for _mem_activity=scan _mem_action=aldb_i2read
> 17/02/2013 14:31:49  [Insteon::ALDB_i2] DEBUG3: $fmr_outside [0x0fd7] received ack
> 17/02/2013 14:32:00 : Saving object states ... done
> 
> It looks like Saving objects causes the scan to stop.

I tried one more scan by using wireless only (I plugged my PLM into a
filter).

It died like this:
17/02/2013 16:03:33  [Scan all link tables] Now scanning: $entryway (12 of 27)
17/02/2013 16:03:34  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:34  [Insteon::ALDB_i2] DEBUG4: Start of scan; initializing aldb structure
17/02/2013 16:03:35  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:36  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:37  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:39  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:39  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:41  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:42  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:44  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:45  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:45  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fb7] ack not received. ignoring message
17/02/2013 16:03:46  [Insteon::BaseInterface] WARN: received insteon ACK/NACK message from $entryway but cannot correlate to sent message! IGNORING received message!!
17/02/2013 16:03:46  [Insteon::BaseInterface] WARN: received insteon ACK/NACK message from $entryway but cannot correlate to sent message! IGNORING received message!!

Uneditted, it looks like this:
17/02/2013 16:03:42  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:42  [Insteon::BaseInterface] DEBUG: PLM command:insteon_ext_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:42  [Insteon::BaseInterface] Processing message for $entryway
17/02/2013 16:03:42  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fc7] received: 0001010fc701e2010e0749ff000400 for _mem_activity=scan _mem_action=aldb_i2readack
17/02/2013 16:03:42  [Insteon::ALDB_i2] DEBUG4: active link found; adding address [0fc7] to aldb
17/02/2013 16:03:42  [Insteon_PLM] DEBUG2: Sending obj=$entryway; command=read_write_aldb; extra=0000000fbf01000000000000000000 incurred delay of 0.07 seconds; starting hop-count: 3
17/02/2013 16:03:42  [Insteon_PLM] DEBUG3: Sending  PLM raw data: 02620f23021f2f0000000fbf01000000000000000000
17/02/2013 16:03:42  [Insteon_PLM] DEBUG4:
         PLM Command: (0262) insteon_send
              To Address: 0f:23:02
           Message Flags: 1f
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 3
                    Max Hops: 3
         Insteon Message: 2f0000000fbf01000000000000000000
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 00000fbf01000000000000000000

17/02/2013 16:03:42  [Insteon_PLM] DEBUG3: Received PLM raw data: 02620f23021f2f0000000fbf0100000000000000000006
17/02/2013 16:03:42  [Insteon_PLM] DEBUG4:
         PLM Command: (0262) insteon_send
              To Address: 0f:23:02
           Message Flags: 1f
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 3
                    Max Hops: 3
         Insteon Message: 2f0000000fbf01000000000000000000
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 00000fbf0100000000000000000006
        PLM Response: (06) ACK

17/02/2013 16:03:42  [Insteon_PLM] DEBUG3: Received PLM acknowledge: obj=$entryway; command=read_write_aldb; extra=0000000fbf01000000000000000000
17/02/2013 16:03:44  [Insteon_PLM] DEBUG3: Received PLM raw data: 02500f230218d4ce212f00
17/02/2013 16:03:44  [Insteon_PLM] DEBUG4:
         PLM Command: (0250) insteon_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 21
                Message Type: (001) ACK of Direct Message
              Message Length: (0) Standard Length
                   Hops Left: 0
                    Max Hops: 1
         Insteon Message: 2f00
                       Cmd 1: (2f) Light OFF at Ramp Rate
                       Cmd 2: (00) Ramp Rate

17/02/2013 16:03:44  [Insteon::BaseInterface] DEBUG3: PLM command:insteon_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:44  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fbf] received: 00 for _mem_activity=scan _mem_action=aldb_i2read
17/02/2013 16:03:44  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fbf] received ack
17/02/2013 16:03:44  [Insteon_PLM] DEBUG3: Received PLM raw data: 02510f230218d4ce112f0001010fbf01e2010e0912ff000400
17/02/2013 16:03:44  [Insteon_PLM] DEBUG4:
         PLM Command: (0251) insteon_ext_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 11
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 0
                    Max Hops: 1
         Insteon Message: 2f0001010fbf01e2010e0912ff000400
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 01010fbf01e2010e0912ff000400

17/02/2013 16:03:44  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:44  [Insteon::BaseInterface] DEBUG: PLM command:insteon_ext_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:44  [Insteon::BaseInterface] Processing message for $entryway
17/02/2013 16:03:44  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fbf] received: 0001010fbf01e2010e0912ff000400 for _mem_activity=scan _mem_action=aldb_i2readack
17/02/2013 16:03:44  [Insteon::ALDB_i2] DEBUG4: active link found; adding address [0fbf] to aldb
17/02/2013 16:03:44  [Insteon_PLM] DEBUG2: Sending obj=$entryway; command=read_write_aldb; extra=0000000fb701000000000000000000 incurred delay of 0.06 seconds; starting hop-count: 3
17/02/2013 16:03:44  [Insteon_PLM] DEBUG3: Sending  PLM raw data: 02620f23021f2f0000000fb701000000000000000000
17/02/2013 16:03:44  [Insteon_PLM] DEBUG4:
         PLM Command: (0262) insteon_send
              To Address: 0f:23:02
           Message Flags: 1f
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 3
                    Max Hops: 3
         Insteon Message: 2f0000000fb701000000000000000000
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 00000fb701000000000000000000

17/02/2013 16:03:44  [Insteon_PLM] DEBUG3: Received PLM raw data: 02620f23021f2f0000000fb70100000000000000000006
17/02/2013 16:03:44  [Insteon_PLM] DEBUG4:
         PLM Command: (0262) insteon_send
              To Address: 0f:23:02
           Message Flags: 1f
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 3
                    Max Hops: 3
         Insteon Message: 2f0000000fb701000000000000000000
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 00000fb70100000000000000000006
        PLM Response: (06) ACK

17/02/2013 16:03:44  [Insteon_PLM] DEBUG3: Received PLM acknowledge: obj=$entryway; command=read_write_aldb; extra=0000000fb701000000000000000000
17/02/2013 16:03:45  [Insteon_PLM] DEBUG3: Received PLM raw data: 02510f230218d4ce122f0001010fb70102030a39d1ff000000
17/02/2013 16:03:45  [Insteon_PLM] DEBUG4:
         PLM Command: (0251) insteon_ext_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 12
                Message Type: (000) Direct Message
              Message Length: (1) Extended Length
                   Hops Left: 0
                    Max Hops: 2
         Insteon Message: 2f0001010fb70102030a39d1ff000000
                       Cmd 1: (2f) Read/Write ALL-Link Database
                       Cmd 2: (00) Command in D2
                      D1-D14: 01010fb70102030a39d1ff000000

17/02/2013 16:03:45  [Insteon::InsteonMessage] WARN: Message has invalid checksum
17/02/2013 16:03:45  [Insteon::BaseInterface] DEBUG: PLM command:insteon_ext_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:45  [Insteon::BaseInterface] Processing message for $entryway
17/02/2013 16:03:45  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fb7] received: 0001010fb70102030a39d1ff000000 for _mem_activity=scan _mem_action=aldb_i2read
17/02/2013 16:03:45  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fb7] ack not received. ignoring message
17/02/2013 16:03:46  [Insteon_PLM] DEBUG3: Received PLM raw data: 02500f230218d4ce232f00
17/02/2013 16:03:46  [Insteon_PLM] DEBUG4:
         PLM Command: (0250) insteon_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 23
                Message Type: (001) ACK of Direct Message
              Message Length: (0) Standard Length
                   Hops Left: 0
                    Max Hops: 3
         Insteon Message: 2f00
                       Cmd 1: (2f) Light OFF at Ramp Rate
                       Cmd 2: (00) Ramp Rate

17/02/2013 16:03:46  [Insteon::BaseInterface] DEBUG3: PLM command:insteon_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:46  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fb7] received: 00 for _mem_activity=scan _mem_action=aldb_i2read
17/02/2013 16:03:46  [Insteon::ALDB_i2] DEBUG3: $entryway [0x0fb7] received ack
17/02/2013 16:03:46  [Insteon_PLM] DEBUG3: Received PLM raw data: 02500f230218d4ce272f00
17/02/2013 16:03:46  [Insteon_PLM] DEBUG4:
         PLM Command: (0250) insteon_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 27
                Message Type: (001) ACK of Direct Message
              Message Length: (0) Standard Length
                   Hops Left: 1
                    Max Hops: 3
         Insteon Message: 2f00
                       Cmd 1: (2f) Light OFF at Ramp Rate
                       Cmd 2: (00) Ramp Rate

17/02/2013 16:03:46  [Insteon::BaseInterface] DEBUG2: Message received with 1 hops left, delaying next transmit to avoid collisions with remaining hops.
17/02/2013 16:03:46  [Insteon::BaseInterface] DEBUG3: PLM command:insteon_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:46  [Insteon::BaseInterface] WARN: received insteon ACK/NACK message from $entryway but cannot correlate to sent message! IGNORING received message!!
17/02/2013 16:03:46  [Insteon_PLM] DEBUG3: Received PLM raw data: 02500f230218d4ce272f00
17/02/2013 16:03:46  [Insteon_PLM] DEBUG4:
         PLM Command: (0250) insteon_received
            From Address: 0f:23:02
              To Address: 18:d4:ce
           Message Flags: 27
                Message Type: (001) ACK of Direct Message
              Message Length: (0) Standard Length
                   Hops Left: 1
                    Max Hops: 3
         Insteon Message: 2f00
                       Cmd 1: (2f) Light OFF at Ramp Rate
                       Cmd 2: (00) Ramp Rate

17/02/2013 16:03:46  [Insteon::BaseInterface] DEBUG2: Message received with 1 hops left, delaying next transmit to avoid collisions with remaining hops.
17/02/2013 16:03:46  [Insteon::BaseInterface] DEBUG3: PLM command:insteon_received; Device command:read_write_aldb; type:direct; group: 
17/02/2013 16:03:46  [Insteon::BaseInterface] WARN: received insteon ACK/NACK message from $entryway but cannot correlate to sent message! IGNORING received message!!

Scan stopped here.

Hope this helps, I'll stop for now.

Marc
-- 
"A mouse is a device used to point at the xterm you want to type in" - A.S.R.
Microsoft is to operating systems ....
                                      .... what McDonalds is to gourmet cooking
Home page: http://marc.merlins.org/  