HomeGenie Forum

General Category => Troubleshooting and Support => Topic started by: JerryR on October 09, 2015, 03:31:04 AM

Title: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 09, 2015, 03:31:04 AM
I have been using HG with a Raspberry Pi (Rev B) for some time now and recently I noticed that none of the RF commands are updating in the HG log. I have rebooted, powered off and on and even unplugged the CM15 Pro from my USB hub and back in. Nothing seems to help and I found the following entries in my homegenie/log file...

  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0
2015-10-08T06:56:35.4519790-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 ! Submit Async Read Failed.
2015-10-08T06:56:35.4529220-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0
  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0
2015-10-08T06:56:35.4566400-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 ! Submit Async Read Failed.
2015-10-08T06:56:35.4575480-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0
  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0
2015-10-08T06:56:35.4609560-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 ! Submit Async Read Failed.
2015-10-08T06:56:35.4618420-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output  X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0

Does this suggest any specific problem or course of action to run this issue down?
I have not made any changes to my HG setup nor my RPi environment during the time in which it quit working.

Any suggestions are very appreciated.

Jerry

I also get these messages and I have no idea what they are about...

2015-10-08T07:05:55.5206300-05:00       MIG.Gateways.WebServiceGateway  192.168.1.252   hg/html/js/codemirror-4.03/addon/fold/foldcode.js       HTTP    GET
2015-10-08T07:05:55.5392780-05:00       MIG.Gateways.WebServiceGateway  192.168.1.252   hg/html/js/codemirror-4.03/addon/fold/brace-fold.js     HTTP    GET
2015-10-08T07:05:55.5569440-05:00       HomeGenie.System        Console StdOut/StdErr redirect  Console.Output
MIGService ERROR: Object reference not set to an instance of an object
  at MIG.MIGService+<PutWebFileCache>c__AnonStorey2.<>m__0 (MIG.WebFileCache wfc) [0x00000] in <filename unknown>:0
  at System.Collections.Generic.List`1[MIG.WebFileCache].Find (System.Predicate`1 match) [0x00000] in <filename unknown>:0
  at MIG.MIGService.PutWebFileCache (System.String file, System.String content, System.Text.Encoding encoding) [0x00000] in <filename unknown>:0
  at MIG.MIGService.webGateway_ProcessRequest (System.Object gwRequest) [0x00000] in <filename unknown>:0
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 09, 2015, 04:28:13 PM
Are there some debug options I should turn on to better diagnose this?
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 09, 2015, 08:03:55 PM
Activate the logging file in the Configuration - Maintenance section.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 10, 2015, 12:27:36 AM
Thanks for the reply! I took a look at the log that can be dumped using that option you referenced. I don't see anything that strikes me as unusual in the contents aside from the object reference that is failing which I mentioned in my first post. When the program starts, I see this:

#Version: 1.0      
#Software: HomeGenie 1.0.0.0      
#Start-Date: 2015-10-08T22:51:10.3241060-05:00      
"#Fields: datetime   source-domain   source-id   description   property   value"      
      
"2015-10-08T22:51:10.1698430-05:00   HomeAutomation.HomeGenie   0   HomeGenie service ready   SystemInfo.HttpPort   8085"      
"2015-10-08T22:51:17.6103220-05:00   HomeAutomation.HomeGenie.Automation   6   Automation Program   Program.Status   Idle"      

Later on in the log I see this

"2015-10-08T22:51:21.6841900-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < 9B 15 33 0B 18 90 09 02"      
"2015-10-08T22:51:21.6966290-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < 9B 15 33 0B 18 90 09 02"      
"2015-10-08T22:51:21.7059350-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < BB 40 00 05 00 14 20 28 24 29"      
"2015-10-08T22:51:21.7094970-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < 8B"      
"2015-10-08T22:51:21.8641120-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 > 55"      
"2015-10-08T22:51:21.8649470-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < 00"      
"2015-10-08T22:51:21.8714490-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 > 00 00 00 00 00 00 00 00"      
"2015-10-08T22:51:21.8720320-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 < 00"      

"2015-10-08T22:51:25.3524420-05:00   HomeGenie.UpdateChecker   0   HomeGenie Update Checker   InstallProgress.Update   STARTED"      
"2015-10-08T22:51:23.6012720-05:00   HomeAutomation.HomeGenie.Automation   6   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:25.6311250-05:00   HomeAutomation.HomeGenie.Automation   7   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:26.3410890-05:00   HomeAutomation.HomeGenie.Automation   84   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:26.9056200-05:00   HomeAutomation.HomeGenie.Automation   84   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:27.0396990-05:00   HomeAutomation.HomeGenie.Automation   91   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:22.7737770-05:00   HomeAutomation.HomeGenie.Automation   122   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:25.7658640-05:00   HomeAutomation.HomeGenie.Automation   122   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:24.3418390-05:00   HomeAutomation.HomeGenie.Automation   142   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:26.4034760-05:00   HomeAutomation.HomeGenie.Automation   142   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:24.5685000-05:00   HomeAutomation.HomeGenie.Automation   93   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:26.1927970-05:00   HomeAutomation.HomeGenie.Automation   121   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:27.0582760-05:00   HomeAutomation.HomeGenie.Automation   121   Automation Program   Program.Status   Running"      
"2015-10-08T22:51:23.3507500-05:00   HomeAutomation.HomeGenie.Automation   8   Automation Program   Program.Status   Setup"      
"2015-10-08T22:51:30.7278220-05:00   HomeGenie.UpdateChecker   0   HomeGenie Update Checker   InstallProgress.Update   COMPLETED"      

It seems like the update checker is looking to see if an update is available (which my HG indicates IS available but it seems to complete ok. Then I see some HTML code fetched from MIG.Gateways.WebServiceGateway

After that I see one more round of redirectConsole.OutputX10 messages and then it seems to do nothing after that.

The only error type indication I see is this statement below which is similar to the original post I created...
"2015-10-08T20:06:30.5756000-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output     at MIG.Gateways.WebServiceGateway.Worker (System.Object state) [0x00000] in <filename unknown>:0 System.NullReferenceException: Object reference not set to an instance of an object"
"2015-10-08T20:06:30.5765380-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output     at MIG.MIGService.webGateway_ProcessRequest (System.Object gwRequest) [0x00000] in <filename unknown>:0 "

It indicates some type of object is being referenced but there is no such instance.

Does any of this help to point in the direction of the problem?
Should I try upgrading HG and see if that fixes it?
Up til now, I have not wanted to upgrade it because it was working well.

Jerry

Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 10, 2015, 04:49:46 AM
Ok, so I upgraded HG using the built in upgrade utility.
Unfortunately it didn't resolve my problem with HG not updating from the CM15 PRO.
As I mentioned, it worked fine in the past - in fact it worked fine until about a week ago and then it seemed to stop talking to the CM15PRO.

I have noticed this error in my logfile:
"2015-10-08T06:56:35.1863290-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 ! Submit Async Read Failed."            
"2015-10-08T06:56:35.2226510-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0 "            
  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0             
"2015-10-08T06:56:35.2260650-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 ! Submit Async Read Failed."            
"2015-10-08T06:56:35.2269740-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0 "            
  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0             
"2015-10-08T06:56:35.2314410-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 ! Submit Async Read Failed."            
"2015-10-08T06:56:35.2323670-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   X10 !   at XTenLib.Drivers.CM15.ReadData () [0x00000] in <filename unknown>:0 "            
  at XTenLib.XTenManager.ReaderThreadLoop () [0x00000] in <filename unknown>:0       

Do you think this async read failure is indicating a hardware problem?
My CM15a is plugged into a hub and the hub is plugged into my Raspberry Pi.
I think I will try connecting the CM15A directly and see if that helps.

Any tips are surely welcomed.
Jerry      
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 10, 2015, 05:49:09 AM
I did some digging through the code in x10-lib-dotnet and it appears that my installation is simply having a problem reading data from the CM15.   :P

I suppose that could be because of a failed USB port in the RaspPi, a failed connection to the powered USB hub I am using, or a bad hub, or finally a bad CM15a.

I plan to try directly connecting my CM15 to my RPi and if that works, I will investigate the hub.

I thought I would leave these comments in the hope that it might help someone else in the future that encounters this same problem.

Kind regards,
Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 11, 2015, 02:59:55 PM
You've fairly well troubleshooted your original problem. The two options I'd select now was to try your CM15Pro on a Windows machine to rule out any problems there. The second option would be to do a fresh install of HG on your PI to rule out any broken packages. I try to avoid USB hubs on the PI in general and the built in HG updater can be flaky sometimes.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 11, 2015, 09:15:44 PM
Thank you for the reply.

The read error (Submit Async Read Failed) I was getting seemed like it might be pointing to some type of hardware problem with the CM15 but I wasn't sure.

I did move the CM15 USB connection from the hub to the Pi directly and fiddled with connect/disconnect for both the USB and power connections. It didn't seem to be working regardless of what I tried. However some combination of things did seem to get it working for a few hours over the weekend and then it quit again.

Currently it is not working though it does show up in 'lsusb' and I am not seeing that previous error in the homegenie.log file but the HG doesn't seem to respond to any of the DS10A events. They don't show up in the log and therefore it appears nothing is happening. I did try the online updater - perhaps I should rip it out and reinstall it.... ugh

I liked your idea of trying it with Windows and I will do just that.
You might correct me Pete but I assume you meant for me to test it with HG on Windows ? (or did you have some other program in mind?)

Thanks for your comments and thoughtful suggestions. I will give that a try and let you know how it goes.
I've had this CM15a PRO for quite a long time so maybe it's flaking out but that just seems kind of odd timing-wise and the fact that it does show up in 'lsusb'.

I'll get back to you.
Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 11, 2015, 10:13:55 PM
Ok, so I tried the CM15a with AHP on my Windows 7 machine and it works fine.
However, when I try it with HG on the same machine under Win7, I get this error message in the log (and it is not working with my DS10A devices):

Timestamp   Property   Value   Source   Domain
3:08:40.305 PM   Console.Output   System.NullReferenceException: Object reference not set to an instance of an object. at MIG.MIGService.webGateway_ProcessRequest(Object gwRequest) in c:\Users\IEUser\Desktop\HomeGenie\MigFiles\MIG\MIGService.cs:line 477 at MIG.Gateways.WebServiceGateway.Worker(Object state) in c:\Users\IEUser\Desktop\HomeGenie\MigFiles\MIG\Gateways\WebServiceGateway.cs:line 217   Console   HomeGenie.System


Can anyone tell me what this null object reference is about?

Thanks,
Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 12, 2015, 12:25:59 AM
I'm thinking there's a problem with the latest version of HG. Google HG earlier versions and work your way backwards until you find a stable version that works for you. I'm certainly no HG expert but I tend to stick with a version of any application version that works for me. Libusb drivers are notoriously temperamental and they may well be the source of your problem. Again some of the experts may well want to jump in here.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 13, 2015, 08:05:37 AM
Pete,

Thanks for hanging in there with me and for your helpful suggestions.
Actually I found that the older 1.0 version was having problems working with my CM15 for some reason - after working many months just fine. I'm not sure what changed.

I tried it under Windows like you suggested and it wasn't working there either.

So, I upgraded to 1.1 in Windows and to my surprise it worked. So then I upgraded my RaspPi to the 1.1 version and it worked too.

Thus I am think I am working again!  Thanks so very much for your help.

Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 16, 2015, 01:12:19 AM
Update - I am still finding issues where HG seems to quit talking to the CM15.

Running the 1.1 software, it works sometimes but it is hard to predict why or when it will fail.
It does seem to be indicating some problem with async reading of the USB port when this happens as shown in the previous logs I posted. Those logs were with 1.0 HG.

I have had some limited success by restarting only the CM15. I turn the power off to it leaving my Pi running as well as HG and then sometimes HG will reconnect with the CM15. The amount of time it works after that is widely variable. Over the last few days I found that the connection was lasting merely an hour or two. Today, it has run all day since I reset it this morning around 7AM. So, that's almost 12 hours of successful operation.

I don't know if this could be a problem with the driver talking to the CM15 or something in HG itself (I suspect the driver).
If anyone else is seeing this type of behavior, I would be interested in hearing from you.

Thank you,
Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 16, 2015, 12:13:49 PM
I assume you are plugging the CM15Pro directly into the RPI and not through a powered USB hub.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on October 16, 2015, 04:55:59 PM
Just as a reference point, I have a CM15A connected directly to my RPi and it has been working fine for close to 2 years.  Is it possible the CM15A is failing?  Could you try it with a different system (Windows + AHP perhaps)?
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 16, 2015, 07:52:27 PM
I think the powered USB hubs tend to cause problems particularly when used with x10. Had intermittent problems with one a while back but as soon as I plugged the CM15Pro directly into the RPI haven't had a problem since.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: JerryR on October 18, 2015, 11:58:01 PM
Hi Pete,

Thanks for the replies. I suppose it is possible that the CM15 is having problems that have just developed but odd that it worked for such a long time with no issues.  I wish someone was on here who could comment about the specific errors I am seeing from the x10lib.

2015-10-18 16:53:17.7482 Error System.Exception: Submit Async Write Failed.
  at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
  at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0

Perhaps there isn't much to say beyond the fact that it is having I/O errors but I'm just wondering if anyone else has encountered this. I have found that sometimes if I re-power the CM15 after leaving it off for a minute, the interface will come up for a while with no errors. I will try to set something up with my Windows Pc as you suggested and see how long it will run continuously without failing under Windows.

I have tried the connection directly to the RPi but also through the hub. I can't really say I see too much difference in terms of how long it works before it fails. I really hate to purchase another CM15a until I know for absolutely sure that it is a hardware problem with my existing interface. I wonder if anyone has found a better inter face to use other than the CM15 since they are rather pricey.

Thanks again for the replies. I'll see what I can do with the Windows test.

Jerry
Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on October 19, 2015, 04:36:59 PM
That's where testing in Windows would help.  I'd also recommend trying AHP again to see if it works correctly.  If both Windows and AHP work, it must be something with the RPi configuration.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on October 19, 2015, 08:27:56 PM
Does sound a little like a failing circuit in the CM15Pro. Typical failures like dry solder joints, resistor failure or failing IC chips give these intermittent symptoms. It may be easier and cheaper in man hour terms to buy a replacement unless you feel confident enough to dismantle the unit and test it with a meter.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on October 31, 2015, 08:34:30 PM
I have the same problem with last 1.1 beta, CM15 pro running in a Raspberry PI, so no CM15Pro or windows problem....
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on October 31, 2015, 10:26:15 PM
I reverted some new code. In the new code I used .net45 "Task" and "CancellationToken". It seems that either I am not using this new stuff properly, or it has bad performances on small computers.
The new code XTenLib was running ok on my bananapi, but I could see occasional I/O error reported in the log. Now after reverting the code I don't see this I/O error anymore, so hopefully this code will fix your issue as well.
Please test by replacing the attacched XTenLib.dll in the homegenie/lib/mig folder. Be sure not to have an XTenLib.dll placed in the main homegenie folder.

g.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on October 31, 2015, 11:04:10 PM
Hi Gene, thank you for your evening help :)

Still not working....
Here are the logs:

2015-10-31 23:01:54.0684 Warn Interface I/O error
2015-10-31 23:01:54.0737 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [CLOSED AFTER 0.009 seconds]
2015-10-31 23:01:54.2448 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [OPEN]
2015-10-31 23:01:54.2478 Debug 04-6D
2015-10-31 23:01:54.2485 Warn Interface I/O error
2015-10-31 23:01:54.2485 Debug 06-62
2015-10-31 23:01:54.2496 Warn Interface I/O error
2015-10-31 23:01:54.2542 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [CLOSED AFTER 0.009 seconds]
2015-10-31 23:01:55.0418 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.Off/null/ [OPEN]
2015-10-31 23:01:55.0483 Debug 04-6D
2015-10-31 23:01:55.0490 Warn Interface I/O error
2015-10-31 23:01:55.0497 Debug 06-63
2015-10-31 23:01:55.0504 Warn Interface I/O error
2015-10-31 23:01:55.0518 Info HomeAutomation.X10   A8   -   Status.Level   0
2015-10-31 23:01:55.0672 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.Off/null/ [CLOSED AFTER 0.025 seconds]
2015-10-31 23:01:55.2223 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.Off/null/ [OPEN]
2015-10-31 23:01:55.2253 Debug 04-6D
2015-10-31 23:01:55.2297 Warn Interface I/O error
2015-10-31 23:01:55.2297 Debug 06-63
2015-10-31 23:01:55.2323 Warn Interface I/O error
2015-10-31 23:01:55.2337 Info HomeAutomation.X10   A8   -   Status.Level   0
2015-10-31 23:01:55.2485 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.Off/null/ [CLOSED AFTER 0.026 seconds]
2015-10-31 23:01:56.3360 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [OPEN]
2015-10-31 23:01:56.3387 Debug 04-6D
2015-10-31 23:01:56.3394 Warn Interface I/O error
2015-10-31 23:01:56.3394 Debug 06-62
2015-10-31 23:01:56.3406 Warn Interface I/O error
2015-10-31 23:01:56.3417 Info HomeAutomation.X10   A8   -   Status.Level   1
2015-10-31 23:01:56.3571 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [CLOSED AFTER 0.021 seconds]
2015-10-31 23:01:56.4825 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [OPEN]
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on October 31, 2015, 11:11:41 PM
I am afraid I dunno where the problem is then... anyway can you try showing log of the following transaction:

- disable interface and wait 5 seconds
- enable interface
- switch something on and off

which model is your CM15? US or EU?

g.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on October 31, 2015, 11:22:53 PM
My CM15 is EU.
I have installed 1.1 without restoring previous configuration so it is a fresh instance.

here is the log:

2015-10-31 23:17:41.8847 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/Media.CameraInput/Options.Get/Configuration// [OPEN]
2015-10-31 23:17:41.9224 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/Protocols.UPnP/IsEnabled.Get/// [OPEN]
2015-10-31 23:17:41.9708 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/Protocols.UPnP/IsEnabled.Get/// [CLOSED AFTER 0.048 seconds]
2015-10-31 23:17:41.9502 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.W800RF/Options.Get/Port// [OPEN]
2015-10-31 23:17:41.9979 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.W800RF/Options.Get/Port// [CLOSED AFTER 0.048 seconds]
2015-10-31 23:17:44.4462 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.006 seconds]
2015-10-31 23:17:44.4403 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:17:44.2207 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.X10/IsEnabled.Set/0// [OPEN]
2015-10-31 23:17:44.2223 Debug Disabling Interface HomeAutomation.X10
2015-10-31 23:17:45.4128 Info MIGService.Interfaces   HomeAutomation.X10   MIG Interface   Status.IsEnabled   0
2015-10-31 23:17:45.6440 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.019 seconds]
2015-10-31 23:17:45.6248 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:17:47.2880 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.X10/IsEnabled.Set/0// [CLOSED AFTER 3.067 seconds]
2015-10-31 23:17:54.5969 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:17:54.6028 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.006 seconds]
2015-10-31 23:18:03.9497 Info HomeAutomation.PhilipsHue   1   -   Meter.Watts   0.0
2015-10-31 23:18:03.9737 Info HomeAutomation.PhilipsHue   2   -   Meter.Watts   0.0
2015-10-31 23:18:03.9903 Info HomeAutomation.PhilipsHue   3   -   Meter.Watts   0.0
2015-10-31 23:18:04.6067 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.006 seconds]
2015-10-31 23:18:04.6003 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:18:05.3556 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.X10/IsEnabled.Set/1// [OPEN]
2015-10-31 23:18:05.3572 Debug Enabling Interface HomeAutomation.X10
2015-10-31 23:18:05.3590 Info HomeAutomation.X10
2015-10-31 23:18:05.4070 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:05.4092 Info MIGService.Interfaces   HomeAutomation.X10   MIG Interface   Status.IsEnabled   1
2015-10-31 23:18:05.6267 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.015 seconds]
2015-10-31 23:18:05.6118 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:18:05.8612 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/MIGService.Interfaces/HomeAutomation.X10/IsEnabled.Set/1// [CLOSED AFTER 0.506 seconds]
2015-10-31 23:18:10.4575 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:12.7176 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Automation/Macro.GetDelay/ [CLOSED AFTER 0.005 seconds]
2015-10-31 23:18:12.7128 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Automation/Macro.GetDelay/ [OPEN]
2015-10-31 23:18:12.8069 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Groups.List/Control/ [CLOSED AFTER 0.083 seconds]
2015-10-31 23:18:12.7236 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Groups.List/Control/ [OPEN]
2015-10-31 23:18:13.3464 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Modules.List/ [CLOSED AFTER 0.284 seconds]
2015-10-31 23:18:13.0619 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Modules.List/ [OPEN]
2015-10-31 23:18:13.3590 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Automation/Programs.List/ [OPEN]
2015-10-31 23:18:14.0103 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Automation/Programs.List/ [CLOSED AFTER 0.651 seconds]
2015-10-31 23:18:14.5157 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:14.6108 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.006 seconds]
2015-10-31 23:18:14.6047 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:18:19.7320 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [OPEN]
2015-10-31 23:18:19.7346 Debug 04-6D
2015-10-31 23:18:19.7571 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.On/null/ [CLOSED AFTER 0.025 seconds]
2015-10-31 23:18:19.7366 Info HomeAutomation.X10   A8   -   Status.Level   1
2015-10-31 23:18:19.7354 Warn Interface I/O error
2015-10-31 23:18:19.7354 Debug 06-62
2015-10-31 23:18:19.7346 Warn Interface I/O error
2015-10-31 23:18:20.5575 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:24.5886 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:24.6413 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [CLOSED AFTER 0.04 seconds]
2015-10-31 23:18:24.6010 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/ [OPEN]
2015-10-31 23:18:25.4984 Info HomeAutomation.EnergyMonitor   1   Virtual Module   EnergyMonitor.KwLoad   0.007
2015-10-31 23:18:25.5136 Info HomeAutomation.EnergyMonitor   1   Virtual Module   EnergyMonitor.WattLoad   7.240
2015-10-31 23:18:25.5204 Info HomeAutomation.EnergyMonitor   1   Virtual Module   EnergyMonitor.OperatingDevices   1
2015-10-31 23:18:25.5362 Info HomeAutomation.EnergyMonitor   1   Virtual Module   EnergyMonitor.OperatingLights   0
2015-10-31 23:18:25.5428 Info HomeAutomation.EnergyMonitor   1   Virtual Module   EnergyMonitor.OperatingSwitches   1
2015-10-31 23:18:28.6457 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[] bytesToSend) [0x00000] in <filename unknown>:0
at XTenLib.Drivers.CM15.Open () [0x00000] in <filename unknown>:0
2015-10-31 23:18:29.6172 Info WebServiceGateway   192.168.1.110   HTTP   GET   200 /api/HomeAutomation.X10/A8/Control.Off/null/ [CLOSED AFTER 0.029 seconds]
2015-10-31 23:18:29.5976 Info HomeAutomation.X10   A8   -   Status.Level   0
2015-10-31 23:18:29.5961 Error System.Exception: Submit Async Write Failed.
at XTenLib.Drivers.CM15.WriteData (System.Byte[
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on October 31, 2015, 11:45:36 PM
chris what mono version are you running?
mono --version
your CM15 is same as mine, so I don't see any reason why it shouldn't work.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on November 01, 2015, 12:54:19 AM
Also check libusb version. This is my config:
Code: [Select]
bananapi@lemaker ~ $ dpkg -l | grep libusb
ii  libusb-0.1-4:armhf                                          2:0.1.12-20+nmu1                        armhf        userspace USB programming library
ii  libusb-1.0-0:armhf                                          2:1.0.11-1                              armhf        userspace USB programming library
ii  libusb-1.0-0-dev                                            2:1.0.11-1                              armhf        userspace USB programming library development files
bananapi@lemaker ~ $ mono --version
Mono JIT compiler version 4.0.4 (Stable 4.0.4.1/5ab4c0d Tue Aug 25 23:45:14 UTC 2015)
Copyright (C) 2002-2014 Novell, Inc, Xamarin Inc and Contributors. [url=http://www.mono-project.com]www.mono-project.com[/url]
TLS:           __thread
SIGSEGV:       normal
Notifications: epoll
Architecture:  armel,vfp+hard
Disabled:      none
Misc:          softdebug
LLVM:          supported, not enabled.
GC:            sgen

Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on November 01, 2015, 03:36:47 AM
I'm running mono 3.2.8 and the same versions of libusb.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: emerich on November 01, 2015, 11:57:18 AM
Hi,
i'm having the same problems with CM15Pro. Help would be very appreciated.

br. Christian
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on November 01, 2015, 01:49:42 PM
I am not able to reproduce the issue, so I can't help unless you provide a sistematic way of hitting this issue.
I also wanted to test on a old Raspberry Pi model A, where I had r492 installed.
With 492 no problems and also doing a fresh install of latest r499 no issues at all (both using the XTenLib.dll from r499 package and the one attacched to the previous message).

This is the configuration of my old RPi:
Code: [Select]
pi@raspberrypi ~ $ mono --version
Mono JIT compiler version 3.2.8 (Debian 3.2.8+dfsg-4+rpi1)
Copyright (C) 2002-2014 Novell, Inc, Xamarin Inc and Contributors. [url=http://www.mono-project.com]www.mono-project.com[/url]
TLS:           __thread
SIGSEGV:       normal
Notifications: epoll
Architecture:  armel,vfp+hard
Disabled:      none
Misc:          softdebug
LLVM:          supported, not enabled.
GC:            sgen
pi@raspberrypi ~ $ dpkg -l | grep libusb
ii  libusb-0.1-4:armhf                                          2:0.1.12-20+nmu1                        armhf        userspace USB programming library
ii  libusb-1.0-0:armhf                                          2:1.0.11-1                              armhf        userspace USB programming library
ii  libusb-1.0-0-dev                                            2:1.0.11-1                              armhf        userspace USB programming library development files
ii  libusbmuxd1                                                 1.0.7-2                                 armhf        USB multiplexor daemon for iPhone and iPod Touch devices - library
pi@raspberrypi ~ $

Good luck!
g.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on November 01, 2015, 05:48:06 PM
I tried to use a XTenLib.dll from 493 but apparently it's not compatible as it gives errors on load and doesn't function.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on November 01, 2015, 06:30:25 PM
Hi Gene,

pi@raspberrypi ~ $ mono --version
Mono JIT compiler version 3.2.8 (Debian 3.2.8+dfsg-4+rpi1)
Copyright (C) 2002-2014 Novell, Inc, Xamarin Inc and Contributors. www.mono-project.com (http://www.mono-project.com)
   TLS:           __thread
   SIGSEGV:       normal
   Notifications: epoll
   Architecture:  armel,vfp+hard
   Disabled:      none
   Misc:          softdebug
   LLVM:          supported, not enabled.
   GC:            sgen

pi@raspberrypi ~ $ dpkg -l | grep libusb
ii  libusb-0.1-4:armhf                                          2:0.1.12-20+nmu1                        armhf        userspace USB programming library
ii  libusb-1.0-0:armhf                                          2:1.0.11-1                              armhf        userspace USB programming library
ii  libusb-1.0-0-dev                                            2:1.0.11-1                              armhf        userspace USB programming library development files
ii  libusbmuxd1                                                 1.0.7-2                                 armhf        USB multiplexor daemon for iPhone and iPod Touch devices - library


I can try again from scratch if you need.
Only tell me which Raspbian image to use.

Thank you
Chris

Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on November 01, 2015, 08:04:11 PM
I am not sure this will solve your issue, but if you want to try from scratch, use a wheezy image. With Jessie there will probably be issue with the startup script.



Title: Re: CM15PRO - worked with HG but not working of late
Post by: emerich on November 02, 2015, 09:03:51 AM
Hi all,
after a clean installation (OS and application) it works for me again also with the latest build 499. Can't tell you why, but I'm happy!  ;D

br. Christian
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on November 02, 2015, 03:33:15 PM
Wheezy?

Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on November 02, 2015, 05:23:19 PM
Please provide some basic description of what you did.  I base my installation off the most recent RPi image Gene posted (very old now).  If it's better to install Raspian or Wheezy or something else, that would be good to know.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: Gene on November 02, 2015, 06:42:27 PM
Raspbian Wheezy

https://www.raspberrypi.org/downloads/raspbian/ (https://www.raspberrypi.org/downloads/raspbian/)

Title: Re: CM15PRO - worked with HG but not working of late
Post by: Jens on November 02, 2015, 06:51:57 PM
All,

I have CM15pro connected to raspberry Model B without any issues with X10 devices. From my point of view there is no need to revert any recent changes in XTenLib.

Devices I have include switches, dimmers and shutters as well as X10 RF motion sensor.

Regards
Jens
Title: Re: CM15PRO - worked with HG but not working of late
Post by: petediscrete on November 02, 2015, 07:04:15 PM
Raspbian Wheezy Kernel 3.18.11+ #781 PREMPT Tue April 21 armV61.
Raspberry PI Model B.
In use: HG 1.1 rc499
Attached: CM15Pro x10 light and appliance modules. RPI CSI camera module.
Everything working so far.


Word is, avoid Jessie for the moment.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on November 02, 2015, 08:02:14 PM
I'll try updating the system in case that's the issue.  I don't see why this is an OS issue since in my case the CM15A works mostly and it works 100% with the older versions (e.g., r493).  My issue may be slightly different and is described here:

http://www.homegenie.it/forum/index.php?topic=1148.0 (http://www.homegenie.it/forum/index.php?topic=1148.0)
Title: Re: CM15PRO - worked with HG but not working of late
Post by: chrispazz on November 02, 2015, 11:50:59 PM
I partitioned the old SD Card, installed Wheezy from scratch, updated Raspi-config and installed 1.1 r499.

CM15Pro is running ok now.

Title: Re: CM15PRO - worked with HG but not working of late
Post by: emerich on November 09, 2015, 06:47:36 PM
Sorry for being late, thats exactly what I did. A clean installation from scratch. I'm using mono 4.0.4

br. Christian
Title: Re: CM15PRO - worked with HG but not working of late
Post by: bkenobi on November 09, 2015, 08:37:00 PM
I installed a new copy of Wheezy (5/5/2015 I believe is the latest) and HG.  With both 499 and 500 I still have partially working CM15A.  It works, but when it receives commands there is a 5-10 second delay because HG is not communicating with the controller correctly and resends the command 1-2 times.

For those that found that starting from scratch fixed the problem, any guidance on exactly what you installed would be helpful to figure out what I (and others) are doing differently to get a different result.
Title: Re: CM15PRO - worked with HG but not working of late
Post by: lowerider on November 15, 2015, 05:23:27 PM
I can't say I am saving the same error but I am getting a 'Object reference not set to an instance object' error message. I am running HG r500 on Windows 7.  R491 worked for me but i wanted to try and upgrade. This is the message, it does not seem to be updating the log even though HG is running. I cannot access the website at all. Just wanted to through this out here since it looked like the bug was being worked.

2015-11-15T10:48:48.3876725-05:00   HomeGenie.System   Console   StdOut/StdErr redirect   Console.Output   System.NullReferenceException: Object reference not set to an instance of an object.
   at MIG.MIGService.webGateway_ProcessRequest(Object gwRequest) in c:\Users\IEUser\Desktop\HomeGenie\MigFiles\MIG\MIGService.cs:line 477
   at MIG.Gateways.WebServiceGateway.Worker(Object state) in c:\Users\IEUser\Desktop\HomeGenie\MigFiles\MIG\Gateways\WebServiceGateway.cs:line 217
2015-1