VERA 2 Device Install

[hek]

I haven't seen this behavior. Just out of curiosity... Why is the gateway not available?
I'm afraid we cannot do much about how Vera handles serial communication failures.

[DeltaNu1142]

I'm still having issues getting my Gateway to appear in Vera. I have a Nano with a NRF24L01 radio module. The SerialGateway sketch uploaded fine, and I received a "0;0;4;11;Arduino startup complete" message via Serial Monitor in the IDE.

What should I be looking for in the \dev folder in Vera to see if the Arduino is plugged in? Here's a screen shot of WinSCP: http://i.imgur.com/n6UD0cV.png

Here's what I'm seeing in the UI: http://i.imgur.com/l5Z5eG7.png

I've posted this in the Vera forum, but I realize you're probably trying to drive Arduino-specific questions to this forum...

EDIT: I just noticed that this "1" appears under \dev\usb\tts when the Arduino is plugged in. I just need to get the device to appear: http://i.imgur.com/Pln0mGb.png

[DeltaNu1142]

Troubleshooting...

I added I_Arduino1.xml to the impl_file field for the device, to see if that would do... anything. Nope. I've also configured the baud rate in Vera for the new "blank" device, and I'm getting nothing.

Is it possible that this is attributable to the fact that I'm using a Vera 2 and not a Vera 3?!?

[hek]

It might. I haven't heard anyone that confirmed that it is working on Vera2.
Does Vera2 have the same firmware version as VeraLite/Vera3?

[DeltaNu1142]

@hek I'm on v1.5.622. I'll be disappointed, because I've gone & invested probably $150 in Nanos, radios, and other stuff.

[hek]

That is the same firmware as I have.

When I plug in the gateway this pops up in /dev
crw-rw-rw- 1 root root 188, 0 Apr 14 22:06 ttyUSB0

[DeltaNu1142]

When I plug in, I definitely see a new USB device in dev\usb\tts\ (from memory, I think that's correct). I responded to a question over at the micasaverde forum about my logs... I get no reference to Arduino in my logs upon restart, so something is not right.

[hek]

@DeltaNu1142

It is quite hard to get a grip on what is going wrong on your Vera unit. Are you aware of any other plugins not working on Vera2?

Unfortunately I have no unit to test this on and the micasa-forum does not give much info on general Vera2 incompatibilities when I search.

[DeltaNu1142]

@hek

Everything else I use works... PLEG, Day or Night, Garage Door, AD2USB... I'm also successfully logging to a USB flash drive in the same USB hub as the Arduino is plugged into.

The only other problem I have associated with using a Vera 2 is that the Synology configuration for logging to my NAS won't work as it does for Vera 3.

I'd like to get this working, but I have nothing else to go on.

[hek]

@DeltaNu1142

Did you see any errors in the Vera log when you added the Arduino plugin? I wonder why the device is blank in your picture.. Did you open and save the device files in any editor after download zip-file from github (introducting some encoding problem)?

If this somehow is related to USB problems you should be able to rebuild you gateway and use the Ethernet-mode of the plugin.

[DeltaNu1142]

I'll log again and add the device later today. If the device doesn't add correctly, though, I don't know how using an Ethernet gateway would fix my issue.

Re:device files, no, I just downloaded & then uploaded to Vera.

[hek]

When I looking at the screenshot you posted on micasa I would suspect you have corrupted the plugin files when you downloaded them.

Use this:
https://github.com/mysensors/Vera/archive/master.zip

Unzip and upload all device files again.

[DeltaNu1142]

I've reloaded the plugin files and will try again later.

[DeltaNu1142]

OK, when I got home, I downloaded the ZIP from this site, dropped the files on my desktop, and loaded them to Vera. I checked the box to reboot Luup, and shortly after I hit "Go," I received an "upload successful" message. I refreshed the browser and created the device, ID # 142. The log from the moment I created the device looks like this:

---

08 04/15/14 18:40:11.223 JobHandler_LuaUPnP::HandleActionRequest device: 0 service: urn:micasaverde-com:serviceId:HomeAutomationGateway1 action: CreateDevice <0x641a>
08 04/15/14 18:40:11.224 JobHandler_LuaUPnP::HandleActionRequest argument UpnpDevFilename=D_Arduino1.xml <0x641a>
08 04/15/14 18:40:11.224 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNumParent=0 <0x641a>
08 04/15/14 18:40:11.225 JobHandler_LuaUPnP::HandleActionRequest argument RoomNum=0 <0x641a>
02 04/15/14 18:40:15.075 JobHandler::Run job#97 :getnodedetails_ri 51 dev:139 (0xe42ad8) P:110 S:0 node 51 is 60.50265000 seconds old <0xc04>
06 04/15/14 18:40:17.364 Device_Variable::m_szValue_set device: 139 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastRouteUpdate was: 1397601391 now: 1397601617 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0xc04>
06 04/15/14 18:40:17.365 Device_Variable::m_szValue_set device: 139 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: Neighbors was: now: #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0xc04>
04 04/15/14 18:40:17.368 <0xc04>
50 04/15/14 18:40:19.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:40:19.113 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:40:19.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:40:29.176 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:40:29.178 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:40:29.264 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:40:35.015 ZZZ-POLLING H1,C1,H2,C2,/1/1 <0xc04>
02 04/15/14 18:40:35.018 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
02 04/15/14 18:40:37.362 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
06 04/15/14 18:40:37.612 Device_Variable::m_szValue_set device: 69 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 74 now: 74 #hooks: 0 upnp: 0 v:0x9f6b10/NONE duplicate:1 <0x803>
50 04/15/14 18:40:39.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:40:39.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:40:39.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:40:39.623 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
02 04/15/14 18:40:41.782 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
02 04/15/14 18:40:43.922 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
06 04/15/14 18:40:44.071 Device_Variable::m_szValue_set device: 69 service: urn:upnp-org:serviceId:HVAC_FanOperatingMode1 variable: Mode was: Auto now: Auto #hooks: 0 upnp: 0 v:0x9c5ae0/NONE duplicate:1 <0x803>
02 04/15/14 18:40:46.082 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
02 04/15/14 18:40:48.252 ZW_Send_Data node 2 USING ROUTE 4.0.0.0 <0xc04>
04 04/15/14 18:40:48.404 <0x803>
50 04/15/14 18:40:49.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:40:49.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:40:49.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>

---

...much of the end of that isn't pertinent, but after creating the device, it's still completely blank.

Here's the log from when I plug the Arduino into the USB port:

---

02 04/15/14 18:45:35.017 ZW_Send_Data node 30 NO ROUTE (nil) <0xc04>
04 04/15/14 18:45:35.314 <0x803>
50 04/15/14 18:45:39.162 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:45:39.163 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:45:39.215 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:45:49.149 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:45:49.150 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:45:49.224 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:45:59.112 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:45:59.114 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:45:59.165 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:46:05.017 ZW_Send_Data node 31 NO ROUTE (nil) <0xc04>
04 04/15/14 18:46:05.644 <0x803>
50 04/15/14 18:46:09.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:09.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:09.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:46:19.197 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:19.199 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:19.334 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:46:29.163 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:29.165 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:29.215 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:46:35.017 ZW_Send_Data node 32 NO ROUTE (nil) <0xc04>
04 04/15/14 18:46:35.333 <0x803>
50 04/15/14 18:46:39.218 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:39.219 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:39.292 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:46:49.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:49.113 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:49.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:46:59.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:46:59.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:46:59.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:47:05.017 ZW_Send_Data node 33 USING ROUTE 255.114.101.100 <0xc04>
04 04/15/14 18:47:05.154 <0x803>
50 04/15/14 18:47:09.144 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:09.146 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:09.205 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:47:19.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:19.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:19.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:47:29.110 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:29.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:29.163 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
02 04/15/14 18:47:35.018 ZW_Send_Data node 34 NO ROUTE (nil) <0xc04>
04 04/15/14 18:47:35.663 <0x803>
50 04/15/14 18:47:39.112 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:39.114 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:39.166 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:47:49.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:49.113 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:49.173 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
06 04/15/14 18:47:58.869 Device_Variable::m_szValue_set device: 100 service: urn:micasaverde-com:serviceId:AlarmPartition2 variable: DetailedArmMode was: Ready now: NotReady #hooks: 2 upnp: 0 v:0xa881d8/NONE duplicate:0 <0x7c1c>
07 04/15/14 18:47:58.976 Event::Evaluate 25 Alarm Armed AWAY scene All Lights Off (away) is false repeat 0/-1 <0x7c1c>
06 04/15/14 18:47:59.309 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = 'At_Work_s1', state = false, seq = 1.39756e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Alarm_Disarmed_t6', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_t9', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39657e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39757e+09, oseq = 1.39757e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39759e+09, oseq = 1.39758e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_c2', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_c4', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},} now: {{name = 'At_Work_s1', state = false, seq = 1.39756e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Alarm_Disarmed_t6', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_t9', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39657e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.3976
e+09, oseq = 1.3976e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39757e+09, oseq = 1.39757e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39759e+09, oseq = 1.39758e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_c2', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_c4', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.3976e+09, oseq = 0},} #hooks: 0 upnp: 0 v:0xa715b8/NONE duplicate:0 <0x7c1c>
50 04/15/14 18:47:59.388 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:47:59.389 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:47:59.459 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1397600784 now: 1397602079 #hooks: 0 upnp: 0 v:0xa539c8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:47:59.501 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
06 04/15/14 18:47:59.641 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 0 now: 1 #hooks: 1 upnp: 0 v:0xa522f8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:47:59.790 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = 'At_Work_s1', state = false, seq = 1.39756e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Alarm_Disarmed_t6', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_t9', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39657e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39757e+09, oseq = 1.39757e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39759e+09, oseq = 1.39758e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_c2', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_c4', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.3976e+09, oseq = 0},} now: {{name = 'At_Work_s1', state = false, seq = 1.39756e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Alarm_Disarmed_t6', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_t9', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39657e+09},{name = 'First_
Floor_Motion_Not_Tripped_t11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39757e+09, oseq = 1.39757e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39759e+09, oseq = 1.39758e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39756e+09, oseq = 1.39752e+09},{name = 'Night_c2', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = true, seq = 1.3976e+09, oseq = 1.39759e+09},{name = 'Away_c4', state = false, seq = 1.39759e+09, oseq = 1.3976e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = false, seq = 1.39752e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.3976e+09, oseq = 0},} #hooks: 0 upnp: 0 v:0xa715b8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:02.725 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1397602079 now: 1397602082 #hooks: 0 upnp: 0 v:0xa539c8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:02.771 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 1 now: 1 #hooks: 1 upnp: 0 v:0xa522f8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:03.780 Device_Variable::m_szValue_set device: 100 service: urn:micasaverde-com:serviceId:AlarmPartition2 variable: DetailedArmMode was: NotReady now: Ready #hooks: 2 upnp: 0 v:0xa881d8/NONE duplicate:0 <0x7c1c>
07 04/15/14 18:48:03.782 Event::Evaluate 25 Alarm Armed AWAY scene All Lights Off (away) is false repeat 0/-1 <0x7c1c>
02 04/15/14 18:48:05.016 ZW_Send_Data node 36 NO ROUTE (nil) <0xc04>
04 04/15/14 18:48:05.314 <0x803>
50 04/15/14 18:48:09.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:48:09.112 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:48:09.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
50 04/15/14 18:48:19.111 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:48:19.113 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>
06 04/15/14 18:48:19.164 Device_Variable::m_szValue_set device: 125 service: urn:upnp-org:serviceId:VGhost1 variable: State was: 0 now: 0 #hooks: 0 upnp: 0 v:0xabb398/NONE duplicate:1 <0x4412>
06 04/15/14 18:48:22.957 Device_Variable::m_szValue_set device: 100 service: urn:micasaverde-com:serviceId:AlarmPartition2 variable: DetailedArmMode was: Ready now: NotReady #hooks: 2 upnp: 0 v:0xa881d8/NONE duplicate:0 <0x7c1c>
07 04/15/14 18:48:23.021 Event::Evaluate 25 Alarm Armed AWAY scene All Lights Off (away) is false repeat 0/-1 <0x7c1c>
06 04/15/14 18:48:23.104 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1397602082 now: 1397602103 #hooks: 0 upnp: 0 v:0xa539c8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:23.106 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 1 now: 1 #hooks: 1 upnp: 0 v:0xa522f8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:26.839 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1397602103 now: 1397602106 #hooks: 0 upnp: 0 v:0xa539c8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:26.891 Device_Variable::m_szValue_set device: 105 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 1 now: 1 #hooks: 1 upnp: 0 v:0xa522f8/NONE duplicate:0 <0x7c1c>
06 04/15/14 18:48:27.828 Device_Variable::m_szValue_set device: 100 service: urn:micasaverde-com:serviceId:AlarmPartition2 variable: DetailedArmMode was: NotReady now: Ready #hooks: 2 upnp: 0 v:0xa881d8/NONE duplicate:0 <0x7c1c>
07 04/15/14 18:48:27.830 Event::Evaluate 25 Alarm Armed AWAY scene All Lights Off (away) is false repeat 0/-1 <0x7c1c>
50 04/15/14 18:48:29.112 luup_log:125: VGinfo: Calling main loop with lul_device: 125 <0x4412>
50 04/15/14 18:48:29.114 luup_log:125: VGinitPhase: VacationGhost is disabled, sleep and start over <0x4412>

---

Same result... it still doesn't work.

[hek]

@DeltaNu1142 said:

I don't see the log from where you created device (#142).

[DeltaNu1142]

@hek

That's the first log.

Last night, I removed all Arduino files from Vera via SSH and then restarted. This morning, I re-uploaded all files, restarted, and then created the device. Here's the log, started before I clicked "Create Device" and received a confirmation that device # 143 was created:

---

50 04/16/14 7:03:57.030 luup_log:60: New Status is: 1 <0x400>
06 04/16/14 7:03:57.031 Device_Variable::m_szValue_set device: 60 service: urn:rts-services-com:serviceId:DayTime variable: Target was: 0 now: 1 #hooks: 0 upnp: 0 v:0x9b5f98/NONE duplicate:0 <0x400>
06 04/16/14 7:03:57.032 Device_Variable::m_szValue_set device: 60 service: urn:rts-services-com:serviceId:DayTime variable: Status was: 0 now: 1 #hooks: 2 upnp: 0 v:0x9b6f60/NONE duplicate:0 <0x400>
07 04/16/14 7:03:57.033 Event::Evaluate 28 Daytime scene Daytime is true users:(null) allow:1 <0x400>
08 04/16/14 7:03:57.034 Scene::RunScene running 74 Daytime <0x400>
08 04/16/14 7:03:57.035 JobHandler_LuaUPnP::HandleActionRequest device: 124 service: urn:dcineco-com:serviceId:MSwitch1 action: SetStatus7 <0x400>
08 04/16/14 7:03:57.035 JobHandler_LuaUPnP::HandleActionRequest argument newStatus7=1 <0x400>
07 04/16/14 7:03:57.038 Event::Evaluate 29 Nighttime scene Nighttime is false repeat 0/-1 <0x400>
04 04/16/14 7:03:57.040 <0x400>
06 04/16/14 7:03:57.043 Device_Variable::m_szValue_set device: 124 service: urn:dcineco-com:serviceId:MSwitch1 variable: Status7 was: 0 now: 1 #hooks: 1 upnp: 0 v:0xa8f700/NONE duplicate:0 <0x400>
06 04/16/14 7:03:57.050 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: None now: Day_c1 #hooks: 2 upnp: 0 v:0xa58258/NONE duplicate:0 <0x400>
07 04/16/14 7:03:57.052 Event::Evaluate 20 Garage Door #2 Left Open scene Prowl Notifications is false repeat 0/-1 <0x400>
07 04/16/14 7:03:57.053 Event::Evaluate 21 Garage Door #1 Left Open scene Prowl Notifications is false repeat 0/-1 <0x400>
06 04/16/14 7:03:57.054 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1397646126 now: 1397646237 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x400>
08 04/16/14 7:03:57.055 JobHandler_LuaUPnP::HandleActionRequest device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC action: RunAction <0x400>
08 04/16/14 7:03:57.056 JobHandler_LuaUPnP::HandleActionRequest argument conditionName=Day_c1 <0x400>
06 04/16/14 7:03:57.164 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = 'At_Work_s1', state = true, seq = 1.39765e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = false, seq = 1.3976e+09, oseq = 1.39761e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Alarm_Disarmed_t6', state = false, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.39763e+09, oseq = 1.39765e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = false, seq = 1.39756e+09, oseq = 1.39761e+09},{name = 'Night_t9', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39762e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.39765e+09, oseq = 1.39762e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = false, seq = 1.39756e+09, oseq = 1.39761e+09},{name = 'Night_c2', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = false, seq = 1.3976e+09, oseq = 1.39762e+09},{name = 'Away_c4', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},} now: {{name = 'At_Work_s1', state = true, seq = 1.39765e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = false, seq = 1.3976e+09, oseq = 1.39761e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Alarm_Disarmed_t6', state = false, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.39763e+09, oseq = 1.39765e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_t9', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39762e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, s
eq = 1.39765e+09, oseq = 1.39762e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_c2', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = false, seq = 1.3976e+09, oseq = 1.39762e+09},{name = 'Away_c4', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.39765e+09, oseq = 0},} #hooks: 0 upnp: 0 v:0xa68270/NONE duplicate:0 <0x400>
06 04/16/14 7:03:57.183 Device_Variable::m_szValue_set device: 124 service: urn:dcineco-com:serviceId:MSwitch1 variable: Status8 was: 1 now: 0 #hooks: 1 upnp: 0 v:0xa8fb78/NONE duplicate:0 <0x400>
06 04/16/14 7:03:57.238 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: Day_c1 now: None #hooks: 2 upnp: 0 v:0xa58258/NONE duplicate:0 <0x400>
07 04/16/14 7:03:57.239 Event::Evaluate 20 Garage Door #2 Left Open scene Prowl Notifications is false repeat 0/-1 <0x400>
07 04/16/14 7:03:57.239 Event::Evaluate 21 Garage Door #1 Left Open scene Prowl Notifications is false repeat 0/-1 <0x400>
06 04/16/14 7:03:57.240 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1397646237 now: 1397646237 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x400>
06 04/16/14 7:03:57.255 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = 'At_Work_s1', state = true, seq = 1.39765e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = false, seq = 1.3976e+09, oseq = 1.39761e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Alarm_Disarmed_t6', state = false, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.39763e+09, oseq = 1.39765e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_t9', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39762e+09},{name = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.39765e+09, oseq = 1.39762e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_c2', state = true, seq = 1.39761e+09, oseq = 1.39756e+09},{name = 'Home_c3', state = false, seq = 1.3976e+09, oseq = 1.39762e+09},{name = 'Away_c4', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.39765e+09, oseq = 0},} now: {{name = 'At_Work_s1', state = true, seq = 1.39765e+09, oseq = 1.3976e+09},{name = 'Guest_Mode_On_t3', state = false, seq = 1.38616e+09, oseq = 0},{name = 'Weekend_s2', state = false, seq = 1.3973e+09, oseq = 1.39744e+09},{name = 'Sleep_Mode_On_t4', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_Mode_On_t5', state = false, seq = 0, oseq = 0},{name = 'Home_Mode_On_t1', state = false, seq = 1.3976e+09, oseq = 1.39761e+09},{name = 'Away_Mode_On_t2', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Alarm_Disarmed_t6', state = false, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Alarm_Armed_AWAY_t7', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Garage_Door_Open_c8', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Front_Door_Entry_c9', state = false, seq = 0, oseq = 0},{name = 'First_Floor_Motion_Timeout_c10', state = false, seq = 1.39763e+09, oseq = 1.39765e+09},{name = 'Garage_Door_Entry_c11', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Garage_Door_Entry_At_Night_c12', state = false, seq = 1.39726e+09, oseq = 1.39726e+09},{name = 'Day_t8', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_t9', state = false, seq = 1.39761e+09, oseq = 1.39765e+09},{name = 'Front_Door_PIN_Code_Entered_t10', state = false, seq = 0, oseq = 1.39762e+09},{nam
e = 'First_Floor_Motion_Not_Tripped_t11', state = true, seq = 1.39765e+09, oseq = 1.39762e+09},{name = 'Front_Door_Locked_t12', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Opened_t13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Opened_t14', state = false, seq = 1.3976e+09, oseq = 1.3976e+09},{name = 'Kitchen_Lights_Off_t15', state = true, seq = 1.39762e+09, oseq = 1.39762e+09},{name = 'Garage_Door_1_Left_Open_c13', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_2_Left_Open_c14', state = false, seq = 1.39758e+09, oseq = 1.39759e+09},{name = 'Day_c1', state = true, seq = 1.39765e+09, oseq = 1.39761e+09},{name = 'Night_c2', state = false, seq = 1.39761e+09, oseq = 1.39765e+09},{name = 'Home_c3', state = false, seq = 1.3976e+09, oseq = 1.39762e+09},{name = 'Away_c4', state = false, seq = 1.39761e+09, oseq = 1.39762e+09},{name = 'Entertain_c5', state = false, seq = 0, oseq = 0},{name = 'Sleeping_c6', state = true, seq = 1.39762e+09, oseq = 1.39756e+09},{name = 'Vacation_c7', state = false, seq = 0, oseq = 0},{name = 'Garage_Door_Open_While_Sleeping_c15', state = false, seq = 0, oseq = 0},{name = 'NOW', state = true, seq = 1.39765e+09, oseq = 0},} #hooks: 0 upnp: 0 v:0xa68270/NONE duplicate:0 <0x400>
04 04/16/14 7:03:57.272 <0x400>
06 04/16/14 7:03:57.274 Device_Variable::m_szValue_set device: 98 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ActionsMap was: {} now: {} #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x400>
08 04/16/14 7:03:57.276 JobHandler_LuaUPnP::HandleActionRequest device: 92 service: urn:upnp-org:serviceId:SwitchPower1 action: SetTarget <0x400>
08 04/16/14 7:03:57.276 JobHandler_LuaUPnP::HandleActionRequest argument newTargetValue=0 <0x400>
06 04/16/14 7:03:57.314 Device_Variable::m_szValue_set device: 92 service: urn:upnp-org:serviceId:SwitchPower1 variable: Target was: 1 now: 0 #hooks: 0 upnp: 0 v:0x9f7540/NONE duplicate:0 <0x400>
02 04/16/14 7:03:57.316 ZWJob_SendData UPDATE MANUAL ROUTE 25=0xd30ecf <0x400>
02 04/16/14 7:03:57.320 UPDATE MANUAL ROUTE2 25=0xd30ecf <0xc04>
02 04/16/14 7:03:57.321 ZW_Send_Data node 25 USING ROUTE 255.101.32.50 <0xc04>
08 04/16/14 7:03:57.368 JobHandler_LuaUPnP::HandleActionRequest device: 71 service: urn:upnp-org:serviceId:SwitchPower1 action: SetTarget <0x400>
08 04/16/14 7:03:57.369 JobHandler_LuaUPnP::HandleActionRequest argument newTargetValue=0 <0x400>
06 04/16/14 7:03:57.370 Device_Variable::m_szValue_set device: 71 service: urn:upnp-org:serviceId:SwitchPower1 variable: Target was: 1 now: 0 #hooks: 0 upnp: 0 v:0x9f7540/NONE duplicate:0 <0x400>
02 04/16/14 7:03:57.372 ZWJob_SendData UPDATE MANUAL ROUTE 4=0xd25aff <0x400>
04 04/16/14 7:03:57.376 <0x400>
02 04/16/14 7:03:57.466 UPDATE MANUAL ROUTE2 4=0xd25aff <0xc04>
02 04/16/14 7:03:57.466 ZW_Send_Data node 4 USING ROUTE 41.0.0.0 <0xc04>
02 04/16/14 7:03:57.566 UPDATE MANUAL ROUTE2 25=0xd30ecf <0xc04>
02 04/16/14 7:03:57.567 ZW_Send_Data node 25 USING ROUTE 255.101.32.50 <0xc04>
06 04/16/14 7:03:57.661 Device_Variable::m_szValue_set device: 92 service: urn:upnp-org:serviceId:SwitchPower1 variable: Status was: 1 now: 0 #hooks: 1 upnp: 0 v:0x9f8ab8/NONE duplicate:0 <0x803>
04 04/16/14 7:03:57.665 <0x803>
02 04/16/14 7:03:57.669 UPDATE MANUAL ROUTE2 4=0xd25aff <0xc04>
02 04/16/14 7:03:57.670 ZW_Send_Data node 4 USING ROUTE 41.0.0.0 <0xc04>
06 04/16/14 7:03:57.761 Device_Variable::m_szValue_set device: 71 service: urn:upnp-org:serviceId:SwitchPower1 variable: Status was: 1 now: 0 #hooks: 1 upnp: 0 v:0x9f8ab8/NONE duplicate:0 <0x803>
04 04/16/14 7:03:57.765 <0x803>
02 04/16/14 7:04:13.015 ZW_Send_Data node 8 USING ROUTE 255.0.0.0 <0xc04>
04 04/16/14 7:04:13.183 <0x803>
02 04/16/14 7:04:43.015 ZW_Send_Data node 9 USING ROUTE 255.0.0.0 <0xc04>

---

I have a feeling you're going to see (or not see) the same information as was in the first log from my previous post. I suspect that is the problem, however, I don't know what to attribute it to. Every other plugin works fine, including AD2USB.

[DeltaNu1142]

As a test, I created a Vacation Ghost device, and it worked fine. I can send you the log of that, if you'd like--please message me an email address.

[hek]

In this screenshot:
http://forum.micasaverde.com/index.php/topic,24057.msg170334.html#msg170334

The log says"D_Arduino1.xml so device XXX is offline".

This indicates that there was something that failed when your Vera tried to create the device. It would have been interesting to see te lines just before. It might contain some useful information.

Also check that your Vera2 is no low on memory.

[DeltaNu1142]

Well--these are the logs I'm getting from IP\cgi-bin\cmh\log.sh?device=luaupnp ... that screenshot is from plugging the Arduino into the USB.

As it stands, I don't have a next step to try to troubleshoot this as I've done everything I can think of.

[hek]

Could you ssh into you Vera and check the /etc/cmh-ludl/ folder

According to your screenshot the file-size of D_Arduino.xml is 31508. But it should only be round 1000 bytes (uncompressed).

/etc/cmh-ludl# ls -lart \*_Arduino\*
-rw-r--r--    1 root     root           564 Jan 30 10:39 S_Arduino.xml.lzo
-rw-r--r--    1 root     root          1245 Jan 30 10:39 I_Arduino1.xml.lzo
-rw-r--r--    1 root     root           581 Mar 25 21:32 S_ArduinoNode.xml.lzo
-rw-r--r--    1 root     root           485 Mar 25 21:32 D_ArduinoRelay1.xml.lzo
-rw-r--r--    1 root     root           473 Mar 25 21:32 D_ArduinoNode1.xml.lzo
-rw-r--r--    1 root     root          1873 Mar 26 00:28 D_Arduino1.json.lzo
-rw-r--r--    1 root     root          9512 Mar 26 00:28 L_Arduino.lua.lzo
-rw-r--r--    1 root     root          2011 Mar 26 00:28 D_ArduinoRelay1.json.lzo
-rw-r--r--    1 root     root          1463 Mar 26 00:28 D_ArduinoNode1.json.lzo
-rw-r--r--    1 root     root           575 Mar 26 00:28 D_Arduino1.xml.lzo

[DeltaNu1142]

I'll do that when I get home later, thanks.

[DeltaNu1142]

Here's what I've got... nothing out of the ordinary that I can see--but since the screen shot you referenced, I've gone through, deleted any Arduino files from Vera through WinSCP, then uploaded the files through the Vera UI again. No change in behavior, unfortunately.

SSH Arduino files.png

[stofakiller]

Hi, i got mine to work, when i change the port from 5003 to 80...

[DeltaNu1142]

@stofakiller

Do you mean, the device was blank until you changed the port? Or are you using an Ethernet gateway?

[stofakiller]

Yes, it was blank until i choose port 80...

I choose 192.168.1.166 as ip for my ethernet gateway, but it didn't work for me with the standard port 5003 (192.168.1.166:5003), but when i changed it in the sketch to port 80 and also in the MySensors Plugin, then it worked.

[DeltaNu1142]

I've been attempting to use a USB gateway. I'm unmotivated at this time to try an Ethernet gateway as I don't have any reason to think it would work.

[hek]

No, it is really strange and unfortunate that your Vera2 seems to handle the MySensors plugin differently than on the 3/Lite model. I tried finding some information on the differences but haven't found any on the micasa-forum.

Using the ethernet gateway wouldn't make a difference at this point.

[Nicola Reina]

Hello
I was going to buy a few arduinos supposing that the project works with a Vera 2 (not vera light).

Can you please confirm that someone has it installed successfully on a Vera 2?

Thanks

[hek]

@Nicola-Reina

Perhaps you could try to install the vera-plugin and see what happens. @DeltaNu1142 had problem just getting plugin to startup correctly.

Would be interesting to see if you are experiencing the same thing.

[DeltaNu1142]

I'd like to see what luck you have with it. I'd be willing to "float" you an Arduino Nano and radio if you wanted to try it out.

[Dvbit]

I am Planning to buy some sensor. Is vera 2 supported? Or is it just vera lite ?

[sailhome]

I have been using a VERA 2 with the Arduino usb gateway for two weeks now. I have two nodes, one with a motion sensor and the second node with a humidity/temp sensor. They are working well. I had to reload the VERA UI several times after I uploaded the files to VERA. After the reboots the gateway worked properly. I have more Arduinos and sensors on order, these things are great. Other than affirming that a VERA 2 will work I can't be of much more help.

[hek]

@sailhome

Good to hear. But this makes the problems @DeltaNu1142 experience a mystery.

[Dvbit]

Thanks. So i go a ahead and hold thumbs

[Dvbit]

Vera is temperamental at least ...
I have some plugins that once worked now not. Apparently nothing changed

[hek]

Yeah, Vera is moody sometimes.

[DeltaNu1142]

@sailhome said:

I have been using a VERA 2 with the Arduino usb gateway for two weeks now. I have two nodes, one with a motion sensor and the second node with a humidity/temp sensor. They are working well. I had to reload the VERA UI several times after I uploaded the files to VERA. After the reboots the gateway worked properly. I have more Arduinos and sensors on order, these things are great. Other than affirming that a VERA 2 will work I can't be of much more help.

I wish I could attribute my troubles between this plugin & my Vera 2 to something. I hung it up a while ago. I've since installed ping sensors & then uninstalled them, but they worked as expected while I had them.

Let me ask this... should you be able to install the Arduino plugin without the hardware, and get... anything? All I ever saw in the plugin was a blue circle. It's as if the plugin never configured.

[jmmorgan]

I am just starting to install my plug in for VERA 2. It created the plug in without a problems. When Vera starts the plug in it states that the serial port has to be selected. How do I do that? Under the serial port tab there is only my serial port for my alarm system. If I ssh into vera I can see both USB ports. My alarm port (usbserial.c: PL-2303 converter now attached to ttyUSB1 (or usb/tts/1 for devfs)0 and the gateway USB (usbserial.c: CP210X converter now attached to ttyUSB0 (or usb/tts/0 for devfs)). So I know it connects, but I do not know how to tell the plug in to use that port. Any help would be appreciated.
John

[hek]

It usually pops up on the serial configuration page. You're using a Arduino Nano right?

[jmmorgan]

@hek I am using Arduino Nano, but it does not pop up in the serial configuration page. I have read a lot of the post on USB to serial connection on the Vera forum, but no luck.
JOhn

[jmmorgan]

After much effort, I got it to recognize the serial gateway: Here what I did
Log into WinSCP
Run lsusb
You should see a line similar to this. Bus 001 Device 006: ID 10c4:ea60 Cygnal Integrated Products, Inc. CP210x Composite Device. The 10c4 is the vendor and the ea60 is the product.

Find
/etc/cmh/serproxy.port
Open it up and make sure it has the line
vendor:10c4 product:ea60
If serproxy.port does not exist, manual create it.
Reboot, Reboot and hope.

Now on to the next step. I hope it is easier.

[jmmorgan]

Well, I knew it was to good to be true. The plug-in loads properly without error and I have set the serial port. However, when I hit the start button (is it suppose to change color and stay that color until is stop) and reset the sensor, nothing happens. What is my next step. The sensor is sending a request for node id, which the gateway acknowledges, but it never send the node ID back. I tried looking at the Vera log, but there are just too many entries.

[hek]

@jmmorgan

> grep Arduino /var/log/cmh/LuaUPnP.log

Only shows Arduino related stuff from the log.

[blacey]

When my Vera3 fails to show the USB port for the Gateway (typically after a power cycle), I do the following to get it to recognize it.

1. Unplug the gateway USB cable from the Vera3's USB port
2. Click Reload on the upper right of the Vera dashboard and wait for reload to finish
3. Plug the gateway USB cable into the Vera3's USB port
4. Click Reload on the upper right of the Vera dashboard and wait for the reload to finish
5. Navigate to Develop Apps->Serial Port to configure the port.

I don't know why the Vera removes the USB port if the device is power cycled while the gateway is connected but the steps above seem to reliably resolve it for me.

[jmmorgan]

Here is my log. It seems the gateway startup ok and send the inclusion command. The node requests the ID, which the gateway acknowledge, but nothing happens after that.
jmmlog1.pdf

[hek]

I can't see that the inclusion mode was started in the log.

And what does the log say on the sensor when it requests an id? Does it receive anything?

[jmmorgan]

@hek the sensor send a request for the ID and the gateway acknowledge the request, but never send the id.

[hek]

Hmm.. yes.. it looks like serial communication only works one-way (from Nano to Vera) but not the other direction.
Gateway doesn't even reply on the request for version message.

How is this even possible? Some strange setting in Serial port configuration?

[jmmorgan]

@hek
There is my serial configuration screen.

[wmylionel]

Could try reducing the baud rate between gateway and vera. I had trouble communicating with the serial gateway at the highest speed (only 1 way communication), reducing to 9600 solved it.

[hek]

@wmylionel said:

Could try reducing the baud rate between gateway and vera. I had trouble communicating with the serial gateway at the highest speed (only 1 way communication), reducing to 9600 solved it.

yep, that might worth a try.

To update baud rate on gateway side, edit the following line in Sensor.h

#define BAUD_RATE 115200

[jmmorgan]

@hek
How do you set it to 9600 in the Vera mysensor plug in? If I set the Vera serial port to 9600, the mysensor plug in states that it needs to be set to 115200 and the plug in fails.
Thanks
John

[hek]

Edit the
L_Arduino.lua
You'll find it somewhere at the top.

[jmmorgan]

@hek
I set the L_Arduino.lua to 9600. The mysensor plugin now states to set the it 9600. I set it to 9600 and reload. It again states to set the serial port 9600 (but it is set to 9600), and the plugin fails. Is there something else I need to change?

[BulldogLowell]

@jmmorgan

what value is your serial port configuration page showing now?

[jmmorgan]

@BulldogLowell It shows 9600

[jmmorgan]

@wmylionel: Are you using Vera 2? If so what lines do you have in the /etc/cmh/serproxy.port file?
Thanks
John

[jmmorgan]

Can anyone tell me what setting they have in the /etc/cmh/serproxy.port file?

[hek]

@jmmorgan said:

serproxy.port

I don't have that file.

[jmmorgan]

Does anyone with a Vera2 have that file?