Show Posts

This section allows you to view all posts made by this member. Note that you can only see posts made in areas you currently have access to.


Topics - davegravy

Pages: [1] 2 3 ... 6
1
Users / 12.04 Asterisk Inbound Call Problem
« on: December 07, 2014, 11:51:56 pm »
I managed to get outbound calls working fairly easily by setting up my VOIP account and adding phones in webadmin.

Inbound was a problem as I was getting a "no authority found" error. The only way I discovered to fix it was to abandon the realtime mysql-based iax.conf config and switch instead to a flat-file. Also it was more than a matter of simply porting the lines from the database to the flat file as the mysql configuration was simply wrong. There is no automatically generated

Code: [Select]
[##########]
type=user
context=from-trunk-##########

section in the database (where ########## is my phone number) which apparently is required.

In summary, everything seems to be working now after doing:

1. comment out the line "iax.conf => mysql,general,ast_config" in /etc/asterisk/extconfig.conf
2. move everything from the mysql table for iax.conf to an actual iax.conf file in /etc/asterisk
3. add the code section above to the iax.conf file
4. reload asterisk

Obviously this is a band-aid solution, but it has things going for the time being.


2
Users / Asterisk Hacking Attempts, Firewall letting attack through somehow?
« on: November 25, 2014, 02:58:47 am »
A bit stumped by this. Alblasco can you help me out?

I launched the asterisk console (asterisk -r) and was barraged with multiple instances of this message:

[Nov 24 20:10:38] NOTICE[3002]: chan_sip.c:24905 handle_request_register: Registration from '"3551" <sip:3551@MYIP-censored>' failed for '195.154.32.252:5763' - No matching peer found

195.154.32.252 comes up in google as a known malicious IP

I used to have SIP ports open to external but as a result of this disabled them.  The attack is still somehow getting through.

I managed to block the attack using

Code: [Select]
iptables -A BLOCKLIST -s 195.154.32.252 -j DROP





3
Users / [SOLVED] CT100 ZWave Thermostat Fahrenheit vs Celcius
« on: November 06, 2014, 10:03:40 pm »
Recently I bought a CT100 thermostat and it have it configured and responding via ZWave

The device is detected as a "Standard Thermostat" device template.

When I try to set heating/cooling setpoint temp it seems to interpret values as Fahrenheit even though in webadmin it says setpoint values are Celcius. How can I modify the behavior to allow Celcius values to be sent?

4
Developers / Market Research
« on: November 05, 2014, 05:05:39 pm »
If anyone's interested in tailoring their dev efforts to match what consumers want, here's a potentially useful poll, albeit a disappointing sample size (N=38):

http://robohub.org/smart-homes-my-home-my-comfort-say-readers/

5
Users / [12.04] OpenZWave Aeon ZStick Issue
« on: October 13, 2014, 06:20:17 pm »
I started having an issue this morning with my Zwave setup where I lost communication to my network. In trying to debug I decided to update my install to get the latest changesets which effect OpenZWave.

Following the Zwave log there seems to be communication with the controller as there are polling requests being sent out to all the nodes and responses coming back.

If I send a command to the device via webadmin (e.g download configuration) there is no corresponding action in the log.

Also I'm seeing these which I never noticed before:

Code: [Select]
2014-10-13 12:15:41.262 Info, Node006, Sending (Poll) message (Callback ID=0x13, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 036     10/13/14 12:15:41.287           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:15:46.317           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:15:51.349           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:16:01.389           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:16:06.420           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:16:11.451           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:16:16.481           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
36      10/13/14 12:16:21.625           ZWInterface::OnNotification() : unhandled/ignored notification = 3 <0xb63ffb40>
x02, 0x26, 0x02, 0x25, 0x13, 0xf3

All loglevels are enabled.

I deleted my zwave device, restarted, went through config, and the parent ZWave device is added but none of the nodes get auto-added to the config.

Any ideas?


6
I added this device using PnP via orbiter.  After detection and device creation it appears LMCE is completely unable to communicate with the controller. See log:

http://paste.ubuntu.com/8287313/

This same stick in the same hardware config works on 1004.

Phenigma mentioned in IRC seeing similar issues but got things working by doing a few things including resetting his Z-stick. I will if there's no other choice, but I have all my node info from 1004 on the device and I'd rather not break that.

Not sure if it's abnormal but I've deleted and re-added the device several times, with the dongle in the same physical usb port, and the /dev/ttyUSB2 seems to change (it's shown up as USB0 an USB1 in the past)

Any ideas?


7
Anyone experienced this?

I've tried unplugging and replugging the usb dongle.

dmesg sees it:

Code: [Select]
[ 1668.440102] usb 1-1.4.1: new full-speed USB device number 25 using ehci_hcd
[ 1668.552648] cp210x 1-1.4.1:1.0: cp210x converter detected
[ 1668.644276] usb 1-1.4.1: reset full-speed USB device number 25 using ehci_hcd
[ 1668.753372] usb 1-1.4.1: cp210x converter now attached to ttyUSB0

And it works fine on my 10.04 install.

I created a ticket here:http://svn.linuxmce.org/trac/ticket/2207

8
Installation issues / 12.04 Client Machines Can't Browse Some Websites
« on: August 27, 2014, 04:38:32 pm »
I have my old 10.04 server and a new 12.04 server that I've assembled and am testing (vanilla install, no modifications yet).

I have a win7 client and a win8 client. Both are having intermittent problems accessing certain websites via the new 12.04 core. Specifically, *.tumblr.com (and likely others I just haven't tested thoroughly yet)
  • On the win7 machine, Chrome reports ERR_EMPTY_RESPONSE. Several refreshes and the page will usually load. I tried the same pages in IE and they also don't load but IE gives no useful debugging info.
  • On the win8 machine, Chrome reports ERR_CONNECTION_RESET for these pages.
  • These errors are thrown instantly. There is no time-out.
  • My android phone can access these sites reliably via Chrome.
  • I have no issues with web access on any client with the same configuration through the 10.04 server.
Anyone encountered anything like this or have suggestions to narrow down the issue? I've cleared the browser cache of all my browsers, restarted machines, etc to no avail.

EDIT: Found this which sounds like a similar symptom but I'm not on pppoe so not sure yet how or if this applies https://bbs.archlinux.org/viewtopic.php?id=182527



9
Installation issues / 1204 MythTV - Can't Configure HDHomerun
« on: August 12, 2014, 05:14:05 pm »
On a fresh 1204 DVD install, my tuners are correctly detected and corresponding devices created in LMCE.  In MythTV setup, I can do a channel scan and the expected channels are detected, so communication with tuner appears to be working.

In MythTV setup under "Input Connections" the tuners are not assigned a video source by default. I can manually map this connection, exit the setup, reload router, but the setting does not persist.

Trying to watch live TV, I'm given the error that no tuners are available.
This exact same hardware setup works normally under 1004.

I created a ticket here:

http://svn.linuxmce.org/trac/ticket/2192#ticket

10
Installation issues / 12.04 NAT Port Forwarding Issue
« on: August 12, 2014, 03:13:09 am »
Port forwarding doesn't seem to be working, or I'm not understanding how to configure it in webadmin.

Alblasco, you requested this on IRC but I wasn't around.

https://www.dropbox.com/s/yiae17ycqe246qx/Screenshot%202014-08-11%2021.07.07.png

Also

Code: [Select]
iptables -t nat -vnL
http://paste.ubuntu.com/8022275/



11
Installation issues / 12.04 NAT Speed Issues [SOLVED]
« on: August 05, 2014, 04:26:07 am »
I am limited by my 1204 core to less than 1Mbit/s internet download speed for machines on my internal network.

My ISP is a 30Mbit/s connection. I can get the full download speed when testing from the core itself. I can transfer files from the core to machines on the internal network at well over 30Mbit/s. It appears there is a bottleneck somewhere between the internal NIC and the external NIC (i.e software).

I have a production core running 1004 which does not exhibit this issue. The above issue occurred when I installed 1204 on this same machine (separate hard drive) - so this is a controlled test.

Anyone else experienced this?

EDIT: Disabling ipv6 in 1204 resolved this.

12
Users / Upgrading to 1204 - considerations
« on: July 15, 2014, 06:48:35 pm »
I’m running 1004 and one of my MDs just kicked the bucket. Trying to decide if I should bite the bullet and upgrade to 1204 and buy a newer replacement MD, or try to find some cheap older hardware that will work with 1004.

In terms of moving to 1204, from the ticket list in svn, the only things that jump out at me as maybe being a deal breaker for my family’s use cases are

http://svn.linuxmce.org/trac/ticket/2075
and
http://svn.linuxmce.org/trac/ticket/2132

Have these issues been reproduced by others, or can anyone comment on the usability of myth/usb-uirt in 1204?

Any general comments on the stability of 1204 would be appreciated too.

Cheers,

Dave

13
Users / Absolute Timed Events Broken?
« on: June 16, 2014, 05:04:09 am »
Running 1004. If I create a timed event and reload the router it hangs. Attached DCERouter log and webadmin screenshot is here:

https://www.dropbox.com/s/fdlju2qgop9g0uq/Screenshot%202014-06-15%2023.00.43.png

Is this a bug or am I creating the timed event improperly?


14
Developers / Popcorn Time
« on: April 15, 2014, 06:33:22 pm »
Since we've integrated a torrent client into LinuxMCE already, I don't suppose there's any opposition amongst devs (from a legality POV) to integrating Popcorn Time, which is just an GUI for streaming torrents?

(Assuming of course that it's technically feasible)

http://www.time4popcorn.eu/

15
Users / Doorbell - Double event trigger
« on: January 18, 2014, 10:18:58 pm »
I have a doorbell connected to my GC-100 on input module 4, sensor 1 (shows as 4:1 in the log)

I've created a respond-to event which fires when the sensor is tripped and sends my phone a notification. For some reason, each doorbell push triggers this event twice - creating a double notification - and i'd like to stop this from happening. Here's a log of a single button press and release:

Code: [Select]
10 01/18/14 16:02:23.138 Reply received from GC100: statechange,4:1,1 <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply interpreted as module: 4:1 change to 1 <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: found module of type SENSOR, 1 <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: matches exactly in m:s format <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.139 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 1) default state:  <0xb5f62b70>
21 01/18/14 16:02:23.139 lock(0x9ea41a4) (>956) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.139 acquired(0x9ea41a4) #0 (>956) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.139 unlock(0x9ea41a4) #0 (>956) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.139 removed from map (0x9ea41a4) #1 (>956) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
21 01/18/14 16:02:23.139 lock(0x9ea41a4) (>957) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.139 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:23.149 unlock(0x9ea41a4) #0 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:23.149 Socket::SendMessage type 2 id 9 from 234 to 0 <0xb7765b70>
21 01/18/14 16:02:23.149 acquired(0x9ea41a4) #0 (>957) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.150 unlock(0x9ea41a4) #0 (>957) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.150 removed from map (0x9ea41a4) #0 (>957) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
10 01/18/14 16:02:23.150 Sending command getstate,4:1
 <0xb5f62b70>
21 01/18/14 16:02:23.150 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:23.150 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.150 acquired(0x9ea41a4) #0 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.150 unlock(0x9ea41a4) #0 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:23.150 Socket::SendMessage type 2 id 9 from 240 to 0 <0xb7765b70>
21 01/18/14 16:02:23.150 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:23.150 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.150 acquired(0x9ea41a4) #0 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.150 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
10 01/18/14 16:02:23.219 Receive string: MESSAGE 77             <0xb6f64b70>
10 01/18/14 16:02:23.219 Received MESSAGE 77             0x9ea4058 device: 230 <0xb6f64b70>
10 01/18/14 16:02:23.219 Received Message type 1 ID 192 from 12 to 232 (device: 230) resp 0 <0xb6f64b70>
10 01/18/14 16:02:23.219 Message for Generic Relays RELAY 3:2 passed to Relay <0xb6f64b70>
10 01/18/14 16:02:23.219 Relay Pwr.: target device is 232 <0xb6f64b70>
10 01/18/14 16:02:23.219 gc100::GetPinDeviceID got pin 3:2 device 232 <0xb6f64b70>
10 01/18/14 16:02:23.219 Relay Pwr.: This device is 232 <0xb6f64b70>
21 01/18/14 16:02:23.219 lock(0x9ea4408) (>983) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:23.219 acquired(0x9ea4408) #0 (>983) gc100 gc100.cpp:1198  <0xb6f64b70>
10 01/18/14 16:02:23.219 Relay Pwr.: Found 3:2 in module map <0xb6f64b70>
10 01/18/14 16:02:23.219 Relay Pwr.: Regardless, module ID is 3:2 <0xb6f64b70>
10 01/18/14 16:02:23.219 Sending command setstate,3:2,1
 <0xb6f64b70>
10 01/18/14 16:02:23.300 EventThread <0xb5f62b70>
10 01/18/14 16:02:23.300 Reply received from GC100: state,4:1,1 <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply interpreted as module: 4:1 change to 1 <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: found module of type SENSOR, 1 <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: matches exactly in m:s format <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.300 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 1) default state:  <0xb5f62b70>
21 01/18/14 16:02:23.300 lock(0x9ea41a4) (>984) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.300 acquired(0x9ea41a4) #0 (>984) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.300 unlock(0x9ea41a4) #0 (>984) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.301 removed from map (0x9ea41a4) #0 (>984) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
21 01/18/14 16:02:23.301 lock(0x9ea41a4) (>985) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.301 acquired(0x9ea41a4) #0 (>985) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.301 unlock(0x9ea41a4) #0 (>985) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:23.301 removed from map (0x9ea41a4) #0 (>985) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
21 01/18/14 16:02:23.301 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:23.301 unlock(0x9ea41a4) #0 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:23.301 Socket::SendMessage type 2 id 9 from 234 to 0 <0xb7765b70>
10 01/18/14 16:02:23.301 Socket::SendMessage type 2 id 9 from 240 to 0 <0xb7765b70>
21 01/18/14 16:02:23.301 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:23.301 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.301 acquired(0x9ea41a4) #0 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:23.301 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:23.320 unlock(0x9ea4408) #0 (>983) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:23.320 removed from map (0x9ea4408) #0 (>983) gc100: gc100.cpp:1198  was: 2 size, now 1 Rel: Y Got: Y <0xb6f64b70>
10 01/18/14 16:02:23.338 Receive string: MESSAGE 77             <0xb6f64b70>
10 01/18/14 16:02:23.338 Received MESSAGE 77             0x9ea4058 device: 230 <0xb6f64b70>
10 01/18/14 16:02:23.338 Received Message type 1 ID 192 from 12 to 232 (device: 230) resp 0 <0xb6f64b70>
10 01/18/14 16:02:23.338 Message for Generic Relays RELAY 3:2 passed to Relay <0xb6f64b70>
10 01/18/14 16:02:23.338 Relay Pwr.: target device is 232 <0xb6f64b70>
10 01/18/14 16:02:23.338 gc100::GetPinDeviceID got pin 3:2 device 232 <0xb6f64b70>
10 01/18/14 16:02:23.338 Relay Pwr.: This device is 232 <0xb6f64b70>
21 01/18/14 16:02:23.338 lock(0x9ea4408) (>1012) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:23.338 acquired(0x9ea4408) #0 (>1012) gc100 gc100.cpp:1198  <0xb6f64b70>
10 01/18/14 16:02:23.338 Relay Pwr.: Found 3:2 in module map <0xb6f64b70>
10 01/18/14 16:02:23.338 Relay Pwr.: Regardless, module ID is 3:2 <0xb6f64b70>
10 01/18/14 16:02:23.338 Sending command setstate,3:2,1
 <0xb6f64b70>
10 01/18/14 16:02:23.351 EventThread <0xb5f62b70>
10 01/18/14 16:02:23.351 Reply received from GC100: state,3:2,1 <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply interpreted as module: 3:2 change to 1 <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: found module of type RELAY, 2 <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.351 statechange Reply: no exact match in m:s format, trying global pin number <0xb5f62b70>
05 01/18/14 16:02:23.351 statechange Reply: Sorry, after all that searching, I can't determine which child device should be sent the pin_changed <0xb5f62b70>
10 01/18/14 16:02:23.404 EventThread <0xb5f62b70>
10 01/18/14 16:02:23.405 Reply received from GC100: state,3:2,1 <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply interpreted as module: 3:2 change to 1 <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: found module of type RELAY, 2 <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 1) default state:  <0xb5f62b70>
10 01/18/14 16:02:23.405 statechange Reply: no exact match in m:s format, trying global pin number <0xb5f62b70>
05 01/18/14 16:02:23.405 statechange Reply: Sorry, after all that searching, I can't determine which child device should be sent the pin_changed <0xb5f62b70>
21 01/18/14 16:02:23.438 unlock(0x9ea4408) #0 (>1012) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:23.439 removed from map (0x9ea4408) #0 (>1012) gc100: gc100.cpp:1198  was: 2 size, now 1 Rel: Y Got: Y <0xb6f64b70>
10 01/18/14 16:02:23.455 EventThread <0xb5f62b70>
10 01/18/14 16:02:24.569 Reply received from GC100: statechange,4:1,0 <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply interpreted as module: 4:1 change to 0 <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: found module of type SENSOR, 1 <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: matches exactly in m:s format <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.570 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 0) default state:  <0xb5f62b70>
21 01/18/14 16:02:24.570 lock(0x9ea41a4) (>1014) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.570 acquired(0x9ea41a4) #0 (>1014) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.570 unlock(0x9ea41a4) #0 (>1014) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.570 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.570 unlock(0x9ea41a4) #0 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:24.570 Socket::SendMessage type 2 id 9 from 234 to 0 <0xb7765b70>
21 01/18/14 16:02:24.571 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:24.571 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.571 acquired(0x9ea41a4) #0 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.571 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.570 removed from map (0x9ea41a4) #1 (>1014) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
21 01/18/14 16:02:24.580 lock(0x9ea41a4) (>1016) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.581 acquired(0x9ea41a4) #0 (>1016) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.581 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.581 unlock(0x9ea41a4) #1 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:24.581 Socket::SendMessage type 2 id 9 from 240 to 0 <0xb7765b70>
21 01/18/14 16:02:24.581 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:24.581 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.581 acquired(0x9ea41a4) #1 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.581 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.581 unlock(0x9ea41a4) #0 (>1016) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.581 removed from map (0x9ea41a4) #0 (>1016) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
10 01/18/14 16:02:24.581 Sending command getstate,4:1
 <0xb5f62b70>
10 01/18/14 16:02:24.629 Receive string: MESSAGE 72             <0xb6f64b70>
10 01/18/14 16:02:24.629 Received MESSAGE 72             0x9ea4058 device: 230 <0xb6f64b70>
10 01/18/14 16:02:24.629 Received Message type 1 ID 193 from 12 to 232 (device: 230) resp 0 <0xb6f64b70>
10 01/18/14 16:02:24.629 Message for Generic Relays RELAY 3:2 passed to Relay <0xb6f64b70>
10 01/18/14 16:02:24.629 Relay Pwr.: target device is 232 <0xb6f64b70>
10 01/18/14 16:02:24.629 gc100::GetPinDeviceID got pin 3:2 device 232 <0xb6f64b70>
10 01/18/14 16:02:24.629 Relay Pwr.: This device is 232 <0xb6f64b70>
21 01/18/14 16:02:24.629 lock(0x9ea4408) (>1041) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:24.629 acquired(0x9ea4408) #0 (>1041) gc100 gc100.cpp:1198  <0xb6f64b70>
10 01/18/14 16:02:24.629 Relay Pwr.: Found 3:2 in module map <0xb6f64b70>
10 01/18/14 16:02:24.629 Relay Pwr.: Regardless, module ID is 3:2 <0xb6f64b70>
10 01/18/14 16:02:24.629 Sending command setstate,3:2,0
 <0xb6f64b70>
21 01/18/14 16:02:24.730 unlock(0x9ea4408) #0 (>1041) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:24.730 removed from map (0x9ea4408) #0 (>1041) gc100: gc100.cpp:1198  was: 2 size, now 1 Rel: Y Got: Y <0xb6f64b70>
10 01/18/14 16:02:24.731 EventThread <0xb5f62b70>
10 01/18/14 16:02:24.732 Reply received from GC100: state,4:1,0 <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply interpreted as module: 4:1 change to 0 <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: found module of type SENSOR, 1 <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: matches exactly in m:s format <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.732 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 0) default state:  <0xb5f62b70>
21 01/18/14 16:02:24.732 lock(0x9ea41a4) (>1043) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.732 acquired(0x9ea41a4) #0 (>1043) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.732 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.732 unlock(0x9ea41a4) #1 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:24.732 Socket::SendMessage type 2 id 9 from 234 to 0 <0xb7765b70>
21 01/18/14 16:02:24.733 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:24.733 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.733 acquired(0x9ea41a4) #1 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.733 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.733 unlock(0x9ea41a4) #0 (>1043) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.733 removed from map (0x9ea41a4) #0 (>1043) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
21 01/18/14 16:02:24.733 lock(0x9ea41a4) (>1045) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.733 acquired(0x9ea41a4) #0 (>1045) MessageQueue Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.733 end cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.733 unlock(0x9ea41a4) #1 (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
10 01/18/14 16:02:24.733 Socket::SendMessage type 2 id 9 from 240 to 0 <0xb7765b70>
21 01/18/14 16:02:24.733 relocking (0x9ea41a4) (>149)  <0xb7765b70>
21 01/18/14 16:02:24.733 lock(0x9ea41a4) (>149) MessageQueue: Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.733 acquired(0x9ea41a4) #1 (>149) MessageQueue Command_Impl.cpp:852  <0xb7765b70>
21 01/18/14 16:02:24.733 start cond wait Command_Impl.cpp:852 (0x9ea41a4)  <0xb7765b70>
21 01/18/14 16:02:24.733 unlock(0x9ea41a4) #0 (>1045) MessageQueue: Command_Impl.cpp:845  <0xb5f62b70>
21 01/18/14 16:02:24.733 removed from map (0x9ea41a4) #0 (>1045) MessageQueue: Command_Impl.cpp:845  was: 3 size, now 2 Rel: Y Got: Y <0xb5f62b70>
10 01/18/14 16:02:24.779 Receive string: MESSAGE 72             <0xb6f64b70>
10 01/18/14 16:02:24.779 Received MESSAGE 72             0x9ea4058 device: 230 <0xb6f64b70>
10 01/18/14 16:02:24.779 Received Message type 1 ID 193 from 12 to 232 (device: 230) resp 0 <0xb6f64b70>
10 01/18/14 16:02:24.780 Message for Generic Relays RELAY 3:2 passed to Relay <0xb6f64b70>
10 01/18/14 16:02:24.780 Relay Pwr.: target device is 232 <0xb6f64b70>
10 01/18/14 16:02:24.780 gc100::GetPinDeviceID got pin 3:2 device 232 <0xb6f64b70>
10 01/18/14 16:02:24.780 Relay Pwr.: This device is 232 <0xb6f64b70>
21 01/18/14 16:02:24.780 lock(0x9ea4408) (>1070) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:24.780 acquired(0x9ea4408) #0 (>1070) gc100 gc100.cpp:1198  <0xb6f64b70>
10 01/18/14 16:02:24.780 Relay Pwr.: Found 3:2 in module map <0xb6f64b70>
10 01/18/14 16:02:24.780 Relay Pwr.: Regardless, module ID is 3:2 <0xb6f64b70>
10 01/18/14 16:02:24.780 Sending command setstate,3:2,0
 <0xb6f64b70>
10 01/18/14 16:02:24.784 EventThread <0xb5f62b70>
10 01/18/14 16:02:24.784 Reply received from GC100: state,3:2,0 <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply interpreted as module: 3:2 change to 0 <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: found module of type RELAY, 2 <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.784 statechange Reply: no exact match in m:s format, trying global pin number <0xb5f62b70>
05 01/18/14 16:02:24.784 statechange Reply: Sorry, after all that searching, I can't determine which child device should be sent the pin_changed <0xb5f62b70>
10 01/18/14 16:02:24.834 EventThread <0xb5f62b70>
10 01/18/14 16:02:24.834 Reply received from GC100: state,3:2,0 <0xb5f62b70>
10 01/18/14 16:02:24.834 statechange Reply interpreted as module: 3:2 change to 0 <0xb5f62b70>
10 01/18/14 16:02:24.834 statechange Reply: found module of type RELAY, 2 <0xb5f62b70>
10 01/18/14 16:02:24.834 statechange Reply: testing Command_Impl2 Dev #, 4:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: testing Command_Impl2 Dev #, 4:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: testing Command_Impl2 Dev #, 4:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: testing Command_Impl2 Dev #, 5:1 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: testing Command_Impl2 Dev #, 5:2 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: testing Command_Impl2 Dev #, 5:3 (state 0) default state:  <0xb5f62b70>
10 01/18/14 16:02:24.835 statechange Reply: no exact match in m:s format, trying global pin number <0xb5f62b70>
05 01/18/14 16:02:24.835 statechange Reply: Sorry, after all that searching, I can't determine which child device should be sent the pin_changed <0xb5f62b70>
21 01/18/14 16:02:24.880 unlock(0x9ea4408) #0 (>1070) gc100: gc100.cpp:1198  <0xb6f64b70>
21 01/18/14 16:02:24.880 removed from map (0x9ea4408) #0 (>1070) gc100: gc100.cpp:1198  was: 2 size, now 1 Rel: Y Got: Y <0xb6f64b70>
10 01/18/14 16:02:24.885 EventThread <0xb5f62b70>


I don't think this is an electronic switch bounce because

a) I've filtered bounces with a hardware circuit and
b) the log doesn't show the state switching from 1 to 0, and then back to 1.

Of note from the log:

10   01/18/14 16:02:23.139      statechange Reply interpreted as module: 4:1 change to 1 <0xb5f62b70>
10   01/18/14 16:02:23.300      statechange Reply interpreted as module: 4:1 change to 1 <0xb5f62b70>
10   01/18/14 16:02:24.570      statechange Reply interpreted as module: 4:1 change to 0 <0xb5f62b70>
10   01/18/14 16:02:24.732      statechange Reply interpreted as module: 4:1 change to 0 <0xb5f62b70>

It seems the GC-100 is double-reporting state changes and LMCE doesn't check to see whether each is a legit state change. Has anyone else noticed this?

Suppose I could have my event script check the time stamp it was last executed and abort if it was under a second ago, but that just seems like a bandaid solution.

Ideas?

Pages: [1] 2 3 ... 6