7
« on: September 12, 2008, 03:52:47 am »
OK this is what is happening for me.. and here is the log.
/var/log/pluto/37_Generic_Serial_Device.log(***):sendGetNext ACKED
(***):LnkData Message Received
(***):{"Parameter1"=>"02", "Parameter2"=>"57", "Parameter3"=>"E2", "Parameter4"=>"01", "Parameter5"=>"A5", "Response"=>"LnkData", "Parameter6"=>"00", "Parameter7"=>"00", "Parameter10"=>"FF", "Parameter8"=>"01", "Parameter9"=>"01"}
(***):FOUND X10 DEVICE!!!
(***):record Flags:E2
(***):Controller
(***):Insteon ID:A5, Group:01
(***):Command Completed.
(***):SndIns:Queue:3
(***):out:02 6A Length:2
(***):IN:02 6A 15 Length:3
(***):sendGetNext NACKED
(***):Command Completed.
(***):Entered CheckChildRecordsinPLM
(***):Record: = 0D.71.C5 Group:01
(***):checking to see if it needs to be added as a child...
(***):ExistsinChild:InsteonID:0D.71.C5
(***):ExistsinChild result:TRUE
(***):Record: = 0D.70.E6 Group:01
(***):checking to see if it needs to be added as a child...
(***):ExistsinChild:InsteonID:0D.70.E6
(***):ExistsinChild result:TRUE
(***):Record: = A5.00.00 Group:01
(***):checking to see if it needs to be added as a child...
(***):ExistsinChild:InsteonID:A5.00.00
(***):ExistsinChild result:FALSE
(***):A5.00.00 needs to be added to the child database
(***):addtochild Routne called
(***):Adding A5.00.00 to the child database...
(***):Child is Insteon
(***):PING Sent!
(***):0D.71.C5 exists in PLM
(***):0D.70.E6 exists in PLM
(***):0D.70.E6 exists in PLM
(***):0D.70.E6 exists in PLM
(***):0D.71.C5 exists in PLM
(***):A5.00.00 exists in PLM
(***):A5 needs to be added to PLM
(***):Adding A5 to the PLM database...
(***):SndIns:Queue:4
(***):out:02 6F 40 C2 01 A5 00 00 01 01 FF Length:11
(***):X-----------SndIns: Waiting for response to:MngLnk
(***):X-----------SndIns: Current Queue Length:4
(***):IN:02 6F 40 C2 01 A5 00 00 01 01 FF 15 Length:12
(***):MngLnk => NACK
(***):Command Completed.
(***):SndIns:Queue:3
(***):out:02 62 0D 71 C5 0F 19 00 Length:8
(***):IN:02 62 0D 71 C5 0F 19 00 06 Length:9
(***):IN:02 50 0D 71 C5 0E DB 5D 2B 00 00 Length:11
(***):Command Completed.
(***):Processing 0x19 Status Report
(***):Database Delta=00
(***):Configuration is:00
(***):From:38
(***):current State:
(***):Wanted State:0
(***):ReportStatus: device:38 Status:0
(***):SndIns:Queue:2
05 09/11/08 20:49:35.392 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:49:35.397 #### Pre-Process Queue = 1 <0x42003950>
05 09/11/08 20:49:35.434 _QueueProc Pre - 193 : 0 <0x40800950>
05 09/11/08 20:49:35.434 GSD-Sleep Pre 193 : 0 <0x40800950>
05 09/11/08 20:49:35.434 Process Queue = 1 <0x40800950>
(***):out:02 62 0D 70 E6 0F 19 00 Length:8
(***):------dce--- cmdID:193, cmdFrom:38, cmdTo:38, cmdType:1, Priority:1
(***):Detected Command sent to Self!
05 09/11/08 20:49:35.534 GSD-Sleep Post 193 : 0 <0x40800950>
05 09/11/08 20:49:35.534 _QueueProc Post - 193 : 0 <0x40800950>
(***):IN:02 62 0D 70 E6 0F 19 00 06 Length:9
(***):IN:02 50 0D 70 E6 0E DB 5D 2B 00 00 Length:11
(***):Command Completed.
(***):Processing 0x19 Status Report
(***):Database Delta=00
(***):Configuration is:00
(***):From:39
(***):current State:
(***):Wanted State:0
(***):ReportStatus: device:39 Status:0
(***):SndIns:Queue:1
05 09/11/08 20:49:36.388 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:49:36.389 #### Pre-Process Queue = 1 <0x42003950>
05 09/11/08 20:49:36.430 _QueueProc Pre - 193 : 0 <0x40800950>
05 09/11/08 20:49:36.430 GSD-Sleep Pre 193 : 0 <0x40800950>
05 09/11/08 20:49:36.430 Process Queue = 1 <0x40800950>
(***):out:02 62 A5 00 00 0F 10 00 Length:8
(***):------dce--- cmdID:193, cmdFrom:39, cmdTo:39, cmdType:1, Priority:1
(***):Detected Command sent to Self!
05 09/11/08 20:49:36.527 GSD-Sleep Post 193 : 0 <0x40800950>
05 09/11/08 20:49:36.527 _QueueProc Post - 193 : 0 <0x40800950>
(***):IN:02 62 A5 00 00 0F 10 00 06 Length:9
(***):Got PING ACK
(***):receive timeout=1
(***):receive timeout=2
(***):receive timeout=3
(***):Command Stalled! resetting and retrying
(***):Current Command:1
(***):SndIns:Queue:1
(***):out:02 62 A5 00 00 0F 10 00 Length:8
(***):IN:02 62 A5 00 00 0F 10 00 06 Length:9
(***):Got PING ACK
(***):receive timeout=1
(***):receive timeout=2
(***):receive timeout=3
(***):Command Stalled! resetting and retrying
(***):Current Command:1
(***):SndIns:Queue:1
(***):out:02 62 A5 00 00 0F 10 00 Length:8
(***):IN:02 62 A5 00 00 0F 10 00 06 Length:9
(***):Got PING ACK
(***):receive timeout=1
(***):receive timeout=2
(***):receive timeout=3
(***):Third attempt, Failing Command!
(***):in RemoveCurrentCommand
(***):Current Command:{"Parameter1"=>"A5", "Parameter2"=>"00", "Parameter3"=>"00", "Parameter4"=>"0F", "Command"=>"InsStdMsg", "Parameter5"=>"10", "Parameter6"=>"00"}
(***):ID=A5.00.00
(***):Removing A5.00.00 from child database
(***):Removing from plm
(***):DEBUG:45 32 30 31 41 35 30 30 30 30 30 31 30 31 46 46 Length:16
(***):Found Record, Deleting
(***):{"Parameter1"=>"80", "Parameter2"=>"E2", "Parameter3"=>"01", "Parameter4"=>"A5", "Command"=>"MngLnk", "Parameter5"=>"00", "Parameter6"=>"00", "Parameter7"=>"01", "Parameter8"=>"01", "Parameter9"=>"FF"}
(***):Command Completed.
(***):SndIns:Queue:1
(***):out:02 6F 80 E2 01 A5 00 00 01 01 FF Length:11
(***):IN:02 6F 80 E2 01 A5 00 00 01 01 FF 06 Length:12
(***):MngLnk => ACK
(***):Command Completed.
(***):Queue Empty.
(***):RERUN=TRUE, RESTARTING
(***):SndIns:Queue:1
(***):out:02 62 0D 71 C5 0F 19 00 Length:8
(***):X-----------SndIns: Waiting for response to:SndIns
(***):X-----------SndIns: Current Queue Length:2
(***):X10 does not report status
(***):X-----------SndIns: Waiting for response to:SndIns
(***):X-----------SndIns: Current Queue Length:2
(***):IN:02 62 0D 71 C5 0F 19 00 06 Length:9
(***):IN:02 50 0D 71 C5 0E DB 5D 2B 00 00 Length:11
(***):Command Completed.
(***):Processing 0x19 Status Report
(***):Database Delta=00
(***):Configuration is:00
(***):From:38
(***):current State:0
(***):Wanted State:0
(***):ReportStatus: device:38 Status:0
(***):SndIns:Queue:1
(***):out:02 62 0D 70 E6 0F 19 00 Length:8
(***):IN:02 62 0D 70 E6 0F 19 00 06 Length:9
(***):IN:02 50 0D 70 E6 0E DB 5D 2B 00 00 Length:11
(***):Command Completed.
(***):Processing 0x19 Status Report
(***):Database Delta=00
(***):Configuration is:00
(***):From:39
(***):current State:0
(***):Wanted State:0
(***):ReportStatus: device:39 Status:0
(***):Queue Empty.
(***):RERUN Not needed
(***):Reporting Child Devices
(***):Child:0D.71.C5
(***):Config:["01012700********", "A2010D70E6FE1F00", "E2010D70E6FE1F00"]
(***):Child:0D.70.E6
(***):Config:["01012700********", "E2010D71C5FE1F00"]
(***):Child:A5
(***):Config:["****************"]
(***):0D.71.C5 38
0D.70.E6 38
A5 0
05 09/11/08 20:50:07.711 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:50:07.712 #### Pre-Process Queue = 1 <0x42003950>
05 09/11/08 20:50:07.743 _QueueProc Pre - 192 : 0 <0x40800950>
05 09/11/08 20:50:07.743 GSD-Sleep Pre 192 : 0 <0x40800950>
05 09/11/08 20:50:07.743 Process Queue = 1 <0x40800950>
(***):------dce--- cmdID:192, cmdFrom:0, cmdTo:40, cmdType:1, Priority:1
(***):Processing ON command
(***):------dce--- X10 DEVICE!!!
(***):DeviceID=A5
(***):X10 Byte1:61
(***):SndIns:Queue:2
(***):out:02 63 61 00 Length:4
(***):From:40
(***):current State:
(***):Wanted State:100
(***):ReportStatus: device:40 Status:100
05 09/11/08 20:50:07.905 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:50:07.906 #### Pre-Process Queue = 1 <0x42003950>
05 09/11/08 20:50:07.906 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:50:07.907 GSD-Sleep Post 192 : 0 <0x40800950>
05 09/11/08 20:50:07.907 _QueueProc Post - 192 : 0 <0x40800950>
05 09/11/08 20:50:07.908 #### Pre-Process Queue = 2 <0x42003950>
05 09/11/08 20:50:07.908 GSDMessageTranslator isCmdImplemented = false <0x42003950>
05 09/11/08 20:50:07.909 #### Pre-Process Queue = 3 <0x42003950>
05 09/11/08 20:50:07.909 _QueueProc Pre - 193 : 0 <0x40800950>
05 09/11/08 20:50:07.909 GSD-Sleep Pre 193 : 0 <0x40800950>
05 09/11/08 20:50:07.909 Process Queue = 1 <0x40800950>
(***):------dce--- cmdID:193, cmdFrom:40, cmdTo:40, cmdType:1, Priority:1
(***):Detected Command sent to Self!
05 09/11/08 20:50:07.911 GSD-Sleep Post 193 : 0 <0x40800950>
05 09/11/08 20:50:07.911 _QueueProc Post - 193 : 0 <0x40800950>
05 09/11/08 20:50:07.911 _QueueProc Pre - 192 : 0 <0x40800950>
05 09/11/08 20:50:07.911 GSD-Sleep Pre 192 : 0 <0x40800950>
05 09/11/08 20:50:07.911 Process Queue = 1 <0x40800950>
(***):------dce--- cmdID:192, cmdFrom:40, cmdTo:40, cmdType:1, Priority:1
(***):Detected Command sent to Self!
05 09/11/08 20:50:07.915 GSD-Sleep Post 192 : 0 <0x40800950>
05 09/11/08 20:50:07.915 _QueueProc Post - 192 : 0 <0x40800950>
05 09/11/08 20:50:07.915 _QueueProc Pre - 184 : 0 <0x40800950>
05 09/11/08 20:50:07.915 GSD-Sleep Pre 184 : 0 <0x40800950>
05 09/11/08 20:50:07.915 Process Queue = 1 <0x40800950>
(***):------dce--- cmdID:184, cmdFrom:40, cmdTo:40, cmdType:1, Priority:1
(***):Detected Command sent to Self!
05 09/11/08 20:50:07.919 GSD-Sleep Post 184 : 0 <0x40800950>
05 09/11/08 20:50:07.919 _QueueProc Post - 184 : 0 <0x40800950>
(***):IN:02 63 61 00 06 Length:5
(***):Response detected for SndX10
(***):X10 Command Successfull
(***):Command Completed.
(***):SndIns:Queue:1
(***):out:02 63 62 80 Length:4
(***):IN:15 Length:1
(***):The PLM stopped responding
(***):Please unplug it, and plug it back in
(***):and do a quick reload router.
(***):receive timeout=1
(***):receive timeout=2
(***):receive timeout=3
(***):Command Stalled! resetting and retrying
(***):Current Command:0
(***):SndIns:Queue:1
(***):out:02 63 62 80 Length:4
(***):IN:02 63 62 80 06 Length:5
(***):Response detected for SndX10
(***):X10 Command Successfull
(***):Command Completed.
(***):Queue Empty.
(***):RERUN Not needed
(***):report not needed
i would like to point out that the X10 light never turned on. I have 2 Insteon switches that work A.O.K now that i moved the PLM to a different power node.