How's Insteon working?

[ddamron]

Jimmy,

Check the MODEL number:  you need model 2412S.  This is a SERIAL device,
so chances are, you have the right one.  That Serial to USB Converter should do the trick for you.
Just to be sure, make sure it's a 2412S.

if it's a 2414, that's a PLC, and I haven't implemented those yet.  (Just received one, so sooner than later it will be implemented)

HTH,

Dan

[JimmyGosling]

alright, I do have the 2412S (confirmed). So what have I missed in the setup then.  I've tried plugging it in to both the rooms MD and the core, but I'm not getting any controls on my orbiters.  Any debugging steps that I can go through. 

Here are a couple of screen shots that may give you a better idea of where I'm at

[JimmyGosling]

it appears that what I'm looking for Insteon to do is magically insert itself into my system and start working flawlessly, which of course, is nuts. 

It's quite possible that all this is missing, I'm starting to discover more and more that what I'm missing are the next steps.  Not being familiar with Insteon devices, or X10 for that matter, i don't know how to get them to talk to each other.  I'm  hoping that someone here can bridge the gap for me on Insteon documentation really.  What steps do I follow to actually add a lighting device? a controlled outlet?

thanks again.

[ddamron]

Jimmy,

well, I know for sure it works on the core, I have installed using those steps.  The PnP may not be working (haven't actually tested that), but here's a couple hints for ya.

1.  I see you have Insteon PLM Device 68 above.  All looks good from that screen.  Check to see if you have a log file created..
the log will be:
 /var/log/pluto/68_Generic_Serial_Device.log

and it will be on the CORE (as per your above setup.)

If that log file exists, do this:

tail -f  /var/log/pluto/68_Generic_Serial_Device.log

and reload router (in another window) and watch the log progress..

You should see some OUT: lines in green, and some IN: lines in red..
those signify the actual bytes being sent/received through the serial port (or usb dongle in your case)

If you see green and red lines (in and out) and still can't get it to work, post a section of your log, and I'll look at it.

I have been doing work on the PnP Lighting wizard, and I KNOW that doesn't work currently. (I think I've found the problem)

I'll be updating the wiki within the next few days.

HTH

Dan

[JimmyGosling]

After a bit of searching I found the thread you were posting the same questions too   so I don't feel like I've completely glossed over something, that's reassuring.

I'm seeing all red in the log file though, no inputs.

Code:

10      03/02/08 8:54:01.123            Opening connection to pci0000:00/0000:00:1d.1+1. <0xb5795b90>
01      03/02/08 8:54:01.123            Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5795b90>
01      03/02/08 8:54:01.123            IOPool::handleIteration Open() failed <0xb5795b90>

Next time I'll post back in that other thread to keep us all on point if that's alright.  I think we're at the same point here.
http://forum.linuxmce.org/index.php?topic=3587.0


Also, per your other thread I've entered the port/channel of 12|0B.B2.09.  "0B.B2.09" being a tag I found on the device.  I'm not sure how solid that assumption was that this was the device address though.

Long and short of it.  I don't want to take up your time solving my issues.  I'm willing to wait until you have something more solid to go from.  thanks for your help.

[ddamron]

Jimmy, NO, Don't do 12|0B...
that's a PROGRAMMING thing I was asking about
nothing to do with user interaction.
and, btw, I WAS doing it right.. the PnP part...

[JimmyGosling]

Alright then, I switched back to the port/channel of "1" and tried sending the commands via the web admin

Here are some of the outputs from the log.  Just a snippet, but it's where the regular log changed.

Do I need to do some setup on the devices for the PLM to actually recognize this switch belongs to it?

01      03/02/08 20:47:52.142           IOPool::handleIteration Open() failed <0xb5768b90>
10      03/02/08 20:47:54.021           Receive string: MESSAGE 81             <0xb5fb1b90>
10      03/02/08 20:47:54.021           Received MESSAGE 81             0x80aba40 device: 68 <0xb5fb1b90>
10      03/02/08 20:47:54.021           Received Message type 1 ID 760 from 0 to 68 (device: 68) resp 0 <0xb5fb1b90>
10      03/02/08 20:47:54.022           Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='1905' <0xb5fb1b90>
10      03/02/08 20:47:54.022           Device has no AV properties <0xb5fb1b90>
10      03/02/08 20:47:54.022           Device has no AV properties <0xb5fb1b90>
05      03/02/08 20:47:54.022           GSDMessageTranslator isCmdImplemented = true <0xb5fb1b90>
10      03/02/08 20:47:54.022           ProcessReplicator : not translatable <0xb5fb1b90>
05      03/02/08 20:47:54.022           #### Pre-Process Queue = 1 <0xb5fb1b90>
10      03/02/08 20:47:54.022           Message processed by Translator. <0xb5fb1b90>
05      03/02/08 20:47:54.044           _QueueProc Pre - 760 : 0 <0xb77b4b90>
10      03/02/08 20:47:54.044           _QueueProc ------- 760 <0xb77b4b90>
05      03/02/08 20:47:54.044           GSD-Sleep Pre 760 : 0 <0xb77b4b90>
10      03/02/08 20:47:54.044           Routing Message 760 to 68... <0xb77b4b90>
10      03/02/08 20:47:54.044           Dispatching Message 760 to 68... <0xb77b4b90>
10      03/02/08 20:47:54.044           Command will be handled in Parent device. <0xb77b4b90>
10      03/02/08 20:47:54.044           Routing message ID=760 to device 68. <0xb77b4b90>
05      03/02/08 20:47:54.044           Process Queue = 1 <0xb77b4b90>
10      03/02/08 20:47:54.142           Calling method cmd_351 in class " with parameters <0xb5768b90>
(***):receive timeout=2
10      03/02/08 20:47:54.142           Method call ended. <0xb5768b90>
10      03/02/08 20:47:54.162           Routing message to Ruby Interpreter... <0xb5768b90>
10      03/02/08 20:47:54.162           handleMessage directly <0xb5768b90>
05      03/02/08 20:47:54.162           Parameter: 1 <0xb5768b90>
05      03/02/08 20:47:54.162           Parameter: 193 <0xb5768b90>
05      03/02/08 20:47:54.162           Parameter:  <0xb5768b90>
10      03/02/08 20:47:54.162           Calling method cmd_760 in class " with parameters <0xb5768b90>
(***):#760:id="1"
(***):#760:parameters=""
(***):#760:pk_command="193"
(***):#760:cmd=#<Command:0xb4f49d2c>
(***):X-----------SndIns: Waiting for response to:SndIns
(***):X-----------SndIns: Current Queue Length:2
10      03/02/08 20:47:54.162           Method call ended. <0xb5768b90>
05      03/02/08 20:47:54.164           GSD-Sleep Post 760 : 0 <0xb77b4b90>
05      03/02/08 20:47:54.164           _QueueProc Post - 760 : 0 <0xb77b4b90>
01      03/02/08 20:47:54.177           TranslateSerialUSB pci0000:00/0000:00:1d.1+1 couldn't find a match <0xb5768b90>
10      03/02/08 20:47:54.177           Opening connection to pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:54.177           Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:54.177           IOPool::handleIteration Open() failed <0xb5768b90>
01      03/02/08 20:47:56.181           TranslateSerialUSB pci0000:00/0000:00:1d.1+1 couldn't find a match <0xb5768b90>
10      03/02/08 20:47:56.181           Opening connection to pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:56.181           Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:56.181           IOPool::handleIteration Open() failed <0xb5768b90>
10      03/02/08 20:47:58.181           Calling method cmd_351 in class " with parameters <0xb5768b90>
(***):receive timeout=3
10      03/02/08 20:47:58.182           Method call ended. <0xb5768b90>
01      03/02/08 20:47:58.206           TranslateSerialUSB pci0000:00/0000:00:1d.1+1 couldn't find a match <0xb5768b90>
10      03/02/08 20:47:58.206           Opening connection to pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:58.206           Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:47:58.206           IOPool::handleIteration Open() failed <0xb5768b90>
01      03/02/08 20:48:00.210           TranslateSerialUSB pci0000:00/0000:00:1d.1+1 couldn't find a match <0xb5768b90>
10      03/02/08 20:48:00.210           Opening connection to pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:48:00.210           Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5768b90>
01      03/02/08 20:48:00.210           IOPool::handleIteration Open() failed <0xb5768b90>

[ddamron]

01      03/02/08 20:47:54.177           Failed Opening serial port: pci0000:00/0000:00:1d.1+1. <0xb5768b90>
this line tells me it's a USB Dongle problem..  See if you can use the dongle for something else (ie confirm it works)
HtH

[JimmyGosling]

Well I hooked up the dongle to another box and tried.  It failed (not sure why, could have sworn it worked the other day, but there you have it)

I instead went out and bought a serial card and plugged that in. Still no go, but I have a different log.  Here is what it looks like:

10      03/08/08 9:23:57.749            Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fbfb90>
10      03/08/08 9:23:57.749            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:57.749            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:57.749            AVMessageTranslator::Translate begin <0xb5fbfb90>
10      03/08/08 9:23:57.749                Status : CMD=184, TP=0, TI=0 last input 0 last power 0 <0xb5fbfb90>
10      03/08/08 9:23:57.750            Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fbfb90>
10      03/08/08 9:23:57.750            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:57.750            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:57.750            AVMessageTranslator::Translate end <0xb5fbfb90>
05      03/08/08 9:23:57.750            #### Pre-Process Queue = 1 <0xb5fbfb90>
10      03/08/08 9:23:57.750            Message processed by Translator. <0xb5fbfb90>
05      03/08/08 9:23:57.770            _QueueProc Pre - 184 : 0 <0xb77c2b90>
10      03/08/08 9:23:57.770            _QueueProc ------- 184 <0xb77c2b90>
05      03/08/08 9:23:57.770            GSD-Sleep Pre 184 : 0 <0xb77c2b90>
10      03/08/08 9:23:57.770            Routing Message 184 to 73... <0xb77c2b90>
10      03/08/08 9:23:57.770            Dispatching Message 184 to 73... <0xb77c2b90>
10      03/08/08 9:23:57.770            Command will be handled in Parent device. <0xb77c2b90>
10      03/08/08 9:23:57.770            Routing message ID=184 to device 73. <0xb77c2b90>
05      03/08/08 9:23:57.770            Process Queue = 1 <0xb77c2b90>
10      03/08/08 9:23:57.788            Routing message to Ruby Interpreter... <0xb5776b90>
10      03/08/08 9:23:57.788            handleMessage for child <0xb5776b90>
10      03/08/08 9:23:57.788            Calling method cmd_ReceiveCommandForChild in class " with parameters <0xb5776b90>
(***):------dce--- cmdID:184, cmdFrom:49, cmdTo:73, cmdType:1, Priority:1
(***):Parameter 76 = 100
(***):X10 DEVICE!!! relative Dimming command
(***):DeviceID=1
01      03/08/08 9:23:57.789            Error while calling method: Cannot call class method: cmd_ReceiveCommandForChild
error: undefined method `chr' for nil:NilClass, line: 2007
backtrace:
        in: (eval): 2007
        from (eval):2007:in `cmd_ReceiveCommandForChild'
        from (eval):1968
 <0xb5776b90>
10      03/08/08 9:23:58.636            Receive string: MESSAGE 67             <0xb5fbfb90>
10      03/08/08 9:23:58.636            Received MESSAGE 67             0x80aba40 device: 68 <0xb5fbfb90>
10      03/08/08 9:23:58.636            Received Message type 1 ID 193 from 49 to 73 (device: 68) resp 0 <0xb5fbfb90>
05      03/08/08 9:23:58.636            GSDMessageTranslator isCmdImplemented = false <0xb5fbfb90>
10      03/08/08 9:23:58.637            Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fbfb90>
10      03/08/08 9:23:58.637            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.637            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.637            Got power command for Device: 73 <0xb5fbfb90>
10      03/08/08 9:23:58.637            Using delay of: 0. <0xb5fbfb90>
10      03/08/08 9:23:58.637            Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fbfb90>
10      03/08/08 9:23:58.637            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.637            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.637            AVMessageTranslator::Translate begin <0xb5fbfb90>
10      03/08/08 9:23:58.637                Status : CMD=193, TP=0, TI=0 last input 0 last power 0 <0xb5fbfb90>
10      03/08/08 9:23:58.638            Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fbfb90>
10      03/08/08 9:23:58.638            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.638            Device has no AV properties <0xb5fbfb90>
10      03/08/08 9:23:58.638            AVMessageTranslator::Translate end <0xb5fbfb90>
05      03/08/08 9:23:58.638            #### Pre-Process Queue = 1 <0xb5fbfb90>
10      03/08/08 9:23:58.638            Message processed by Translator. <0xb5fbfb90>
10      03/08/08 9:23:59.158            Calling method cmd_351 in class " with parameters <0xb5776b90>
10      03/08/08 9:23:59.158            Method call ended. <0xb5776b90>

by the way, device #38 is the onscreen orbiter for the room I would like to dim the lights in.

I'm also not running any X10 devices here.  Only the insteon plm and a single dimmer switch.  Any ideas on what to try next?

[hari]

hmm, somehow the translation for the serial port does not work:

Code:

01      03/02/08 20:47:54.177           TranslateSerialUSB pci0000:00/0000:00:1d.1+1 couldn't find a match <0xb5768b90>

that should spit out something like /dev/ttyUSB0

please come to the irc chat, we can try something.

best regards,
Hari

[JimmyGosling]

So, same error, but perhaps a little better presented.  Here is the latest. 

10      03/10/08 20:22:03.749           Calling method cmd_351 in class " with parameters <0xb578bb90>
10      03/10/08 20:22:03.749           Method call ended. <0xb578bb90>
10      03/10/08 20:22:04.251           Receive string: MESSAGE 67             <0xb5fd4b90>
10      03/10/08 20:22:04.251           Received MESSAGE 67             0x80aba40 device: 68 <0xb5fd4b90>
10      03/10/08 20:22:04.251           Received Message type 1 ID 193 from 64 to 73 (device: 68) resp 0 <0xb5fd4b90>
05      03/10/08 20:22:04.251           GSDMessageTranslator isCmdImplemented = false <0xb5fd4b90>
10      03/10/08 20:22:04.252           Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fd4b90>
10      03/10/08 20:22:04.252           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.252           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.252           Got power command for Device: 73 <0xb5fd4b90>
10      03/10/08 20:22:04.252           Using delay of: 0. <0xb5fd4b90>
10      03/10/08 20:22:04.253           Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fd4b90>
10      03/10/08 20:22:04.253           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.253           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.253           AVMessageTranslator::Translate begin <0xb5fd4b90>
10      03/10/08 20:22:04.253               Status : CMD=193, TP=0, TI=0 last input 0 last power 0 <0xb5fd4b90>
10      03/10/08 20:22:04.253           Query returned 0 rows: SELECT IR_PowerDelay FROM DeviceTemplate_AV WHERE FK_DeviceTemplate='38' <0xb5fd4b90>
10      03/10/08 20:22:04.253           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.253           Device has no AV properties <0xb5fd4b90>
10      03/10/08 20:22:04.253           AVMessageTranslator::Translate end <0xb5fd4b90>
05      03/10/08 20:22:04.253           #### Pre-Process Queue = 1 <0xb5fd4b90>
10      03/10/08 20:22:04.253           Message processed by Translator. <0xb5fd4b90>
05      03/10/08 20:22:04.257           _QueueProc Pre - 193 : 0 <0xb77d7b90>
10      03/10/08 20:22:04.257           _QueueProc ------- 193 <0xb77d7b90>
05      03/10/08 20:22:04.257           GSD-Sleep Pre 193 : 0 <0xb77d7b90>
10      03/10/08 20:22:04.257           Routing Message 193 to 73... <0xb77d7b90>
10      03/10/08 20:22:04.257           Dispatching Message 193 to 73... <0xb77d7b90>
10      03/10/08 20:22:04.257           Command will be handled in Parent device. <0xb77d7b90>
10      03/10/08 20:22:04.257           Routing message ID=193 to device 73. <0xb77d7b90>
05      03/10/08 20:22:04.257           Process Queue = 1 <0xb77d7b90>
10      03/10/08 20:22:04.276           Routing message to Ruby Interpreter... <0xb578bb90>
10      03/10/08 20:22:04.276           handleMessage for child <0xb578bb90>
10      03/10/08 20:22:04.276           Calling method cmd_ReceiveCommandForChild in class " with parameters <0xb578bb90>
(***):------dce--- cmdID:193, cmdFrom:64, cmdTo:73, cmdType:1, Priority:1
(***):------dce--- X10 DEVICE!!!
(***):DeviceID=1
01      03/10/08 20:22:04.277           Error while calling method: Cannot call class method: cmd_ReceiveCommandForChild
error: undefined method `chr' for nil:NilClass, line: 1968
backtrace:
        in: (eval): 1968
        from (eval):1968:in `cmd_ReceiveCommandForChild'
        from (eval):2007
 <0xb578bb90>
10      03/10/08 20:22:05.767           Calling method cmd_351 in class " with parameters <0xb578bb90>
10      03/10/08 20:22:05.767           Method call ended. <0xb578bb90>
01      03/10/08 20:22:22.001           Ruby was unable to handle the command in reasonable amount of time <0xb77d7b90>
05      03/10/08 20:22:22.001           GSD-Sleep Post 193 : 0 <0xb77d7b90>
05      03/10/08 20:22:22.001           _QueueProc Post - 193 : 0 <0xb77d7b90>
10      03/10/08 20:22:23.868           Calling method cmd_351 in class " with parameters <0xb578bb90>
10      03/10/08 20:22:23.868           Method call ended. <0xb578bb90>

19 - Core
38 - Room on screen orbiter
68 - PLM
73 - Light Switch

The PLM is connected to the core(19), but is operating the lights in the same room with 38.  I'm no longer using a usb-serial cable, but instead have a serial card installed.

[ddamron]

JimmyGosling is up and running.

[seth]

Any update on X10 working yet? Just curious, I have been off line waiting for a chance to redo my system with the new motherboard. I am downloading beta4, in the hopes that perhaps my old cm11a-like device will function in the meantime.

I keep watching this post. 

[JimmyGosling]

I can't comment on the x10 stuff as I don't have any running in the house, but I would like to thank ddamron for taking the time to help me get this up and running personally.

I would like to know more about the commands you were running and specifically what you were looking for during the process so that I can do the same when I'm adding new devices or likewise trying to "pay off my debt" helping others   I think the wiki would be the ideal place of course.  Let me know if there is anything I can help with on that end (tech writing, validation or otherwise).

Also, I would like to point out what a great resource the IRC is as well.  Not only for getting some one on one help, but really getting to know and enjoying this community.  I've already picked up a a lot of great information that was missing from the wiki and these forums and really only comes up in regular conversation. 

[marrandy]

"JimmyGosling is up and running."

Can either you or Jimmy say what the problem was and how it was made to work.

This could be useful for other people as it's a permanent record.

PS. I've just rx'd my 2412S so I'm hoping to start testing real soon.

Regards and best wishes...Martin