Adding virtual switch manually in UI4? - Solved

neilios · March 22, 2011, 6:00am

In UI2 I had an XML file that I uploaded and selected to add my virtual switch device.

In UI4 it looks like I needs to go to MiOS developers > Create Device…
… but I’m not really sure what needs to go in the fields. I’ve made a reasonable guess at it but the device gets removed shortly after being added.

It all looks like its worked initially (save the changes and the device shows up in the list), but something must happen to have it removed a few seconds or so later as it just disappears from the view.

Is there something fundamental that I’m missing here? I couldn’t find the documentation for it - but then I often can’t, so please point me to it if it’s lurking out there.

I wish it was as easy as UI2

Cheers,
Neil

mcvflorin · March 22, 2011, 11:22am

You only need to populate the Description and UpnpDevFilename fields.

neilios · March 22, 2011, 3:44pm

I had done that as a minimum and still got this behavior.
Is there something new required or different in the xml file?
Is there some particular sequence/procedure I need to follow to get it to work?

Thanks,
Neil

mcvflorin · March 23, 2011, 9:37am

Please attach the XML file so I can have a look.

neilios · March 23, 2011, 4:00pm

It’s attached.
I did get this to work once in UI4/FW v992 - it seemed to pick up the right info and be operational with the exception of not showing the On/Off status.
When I tried to modify it and re-install it did not work and never has since.

Right now, I add the name of the attached Dev file and a description and have it controlled by ZWave when I try to add it.
The new device appears in the UI but then disappears after the changes are “Saved” from the main screen.

I’m assuming that there’s a mis-configuration and something checks that and removes it during the “Save”.

Here’s part of the log file after the restart, where Lua seems to terminate shortly after writing to the device. I do not know if this shows anything useful - maybe the write caused an issue - does the “NONE/duplicate” indicate a problem?:

06 03/23/11 8:48:02.700 Device_Variable::m_szValue_set device: 1 service: urn:upnp-org:serviceId:SwitchPower1 variable: ^[[35;1mStatus^[[0m was: 1 now: 1 #hooks: 0 upnp: 0 v:0x78b2e8/NONE duplicate:1 <0x803>
50 03/23/11 8:48:02.701 luup_log:1: MVRZ_DEBUG:lastPeriod=2 <0x803>
01 03/23/11 8:48:03.720 ^[[31;1mMongoose XXX-mg_stop2 0x78f080 2 1^[[0m <0x400>
01 03/23/11 8:48:03.721 ^[[31;1mMongoose XXX-mg_stop3 0x78f080 2 1^[[0m <0x400>
01 03/23/11 8:48:03.722 ^[[31;1mMongoose XXX-mg_stop4 0x78f080 8327608 1^[[0m <0x400>
03 03/23/11 8:48:03.767 LuaUPNP: ending <0x400>

LuaPNP restarts and the device disappears from the UI. The log shows that the new device is not found during restart.

Cheers,
Neil

mcvflorin · March 25, 2011, 2:01pm

Here's part of the log file after the restart, where Lua seems to terminate shortly after writing to the device. I do not know if this shows anything useful - maybe the write caused an issue - does the "NONE/duplicate" indicate a problem?:
06 03/23/11 8:48:02.700 Device_Variable::m_szValue_set device: 1 service: urn:upnp-org:serviceId:SwitchPower1 variable: ^[[35;1mStatus^[[0m was: 1 now: 1 #hooks: 0 upnp: 0 v:0x78b2e8/NONE duplicate:1 <0x803>

This has nothing to do with it.

I looked over your device file and I can see nothing wrong with it. I tested your device file after I removed the events, scenes and services that used files that I don’t have and there was no problem.

Maybe there’s something wrong in your other files. SSH into Vera (using PuTTY for example), enable verbose logging with the command:

VerboseLogging.sh enable

then look after any errors with the command:

tail -f /tmp/log/cmh/LuaUPnP.log | grep -i '^01\|^50'

Don’t forget to disable verbose logging (VerboseLogging.sh disable) after you finished debugging.

neilios · March 25, 2011, 3:04pm

Thank for the suggestion…

No errors of that type were generated (unfortunately).

What I have be noticing is that there’s a sequence of “Mongoose XXX-mg_stop1…”, stop2, stop3 and stop4 messages just before it terminates. There’s a big gap between the first one that occurs and the final 3 that are close together. Here is where I see the first one:

10 03/25/11 7:46:43.481 mg_callback from IP:127.0.0.1:4087 /port_3480/data_request start id: 2 <0x3c10> 10 03/25/11 7:46:43.482 mg_callback /data_request stop id: 2 <0x3c10> 10 03/25/11 7:46:43.486 UserData::WriteUserData moving old files <0x400> 10 03/25/11 7:46:43.491 MainWatchDogRoutine started 1 <0x4011> 10 03/25/11 7:46:43.590 WatchDogRoutine-1 started 1 <0x4011> 10 03/25/11 7:46:43.647 UserData::WriteUserData doneFile Size: 4847 save size 4847 <0x400> 10 03/25/11 7:46:43.648 UserData::CommitToDatabase done <0x400> 10 03/25/11 7:46:43.649 JobHandler_LuaUPnP::~JobHandler_LuaUPnP 0x714f78 <0x400> 10 03/25/11 7:46:43.650 JobHandler_LuaUPnP::m_bQuit_set now 1 for 0x714f78 ~JobHandler_LuaUPnP <0x400> 10 03/25/11 7:46:43.650 ThreadedClass::m_bQuit_set now 1 for 0x714f78 ~JobHandler_LuaUPnP <0x400> 10 03/25/11 7:46:43.651 AlarmManager::Stop 1 <0x400> 10 03/25/11 7:46:43.652 JobHandler_LuaUPnP::m_bQuit_set now 1 for 0x714f78 ThreadedClass::StopThread ptr 0x714f78 thread (nil) <0x400> 10 03/25/11 7:46:43.653 ThreadedClass::m_bQuit_set now 1 for 0x714f78 ThreadedClass::StopThread ptr 0x714f78 thread (nil) <0x400> 01 03/25/11 7:46:43.653 ^[[31;1mMongoose XXX-mg_stop1 0x71d100 1 1^[[0m <0x400> 10 03/25/11 7:46:44.590 WatchDogRoutine-2 started 1 <0x4011>
Then there’s more stuff and finally:

50 03/25/11 7:46:45.079 50 03/25/11 7:46:45.080 50 03/25/11 7:46:45.121 50 03/25/11 7:46:45.459 50 03/25/11 7:46:45.461 06 03/25/11 7:46:45.467 50 03/25/11 7:46:45.468 50 03/25/11 7:46:45.469 10 03/25/11 7:46:45.469 10 03/25/11 7:46:45.470 01 03/25/11 7:46:45.670 01 03/25/11 7:46:45.671 01 03/25/11 7:46:45.672 10 03/25/11 7:46:45.673 10 03/25/11 7:46:45.673 10 03/25/11 7:46:45.674 10 03/25/11 7:46:45.675 10 03/25/11 7:46:45.676 10 03/25/11 7:46:45.676 10 03/25/11 7:46:45.677 10 03/25/11 7:46:45.678 10 03/25/11 7:46:45.678 10 03/25/11 7:46:45.679 10 03/25/11 7:46:45.680 10 03/25/11 7:46:45.681 10 03/25/11 7:46:45.681 10 03/25/11 7:46:45.682 10 03/25/11 7:46:45.683 10 03/25/11 7:46:45.683 10 03/25/11 7:46:45.685 10 03/25/11 7:46:45.686 10 03/25/11 7:46:45.687 10 03/25/11 7:46:45.688 10 03/25/11 7:46:45.689 10 03/25/11 7:46:45.689 10 03/25/11 7:46:45.711 10 03/25/11 7:46:45.712 10 03/25/11 7:46:45.713 10 03/25/11 7:46:45.714 10 03/25/11 7:46:45.715 10 03/25/11 7:46:45.716 10 03/25/11 7:46:45.716 10 03/25/11 7:46:45.717 10 03/25/11 7:46:45.718 10 03/25/11 7:46:45.719 10 03/25/11 7:46:45.719 10 03/25/11 7:46:45.720 10 03/25/11 7:46:45.721 10 03/25/11 7:46:45.721 10 03/25/11 7:46:45.722 10 03/25/11 7:46:45.723 10 03/25/11 7:46:45.724 10 03/25/11 7:46:45.737 10 03/25/11 7:46:45.742 10 03/25/11 7:46:45.743 10 03/25/11 7:46:45.745 10 03/25/11 7:46:45.746 10 03/25/11 7:46:45.748 10 03/25/11 7:46:45.750 10 03/25/11 7:46:45.752 10 03/25/11 7:46:45.754 10 03/25/11 7:46:45.756 10 03/25/11 7:46:45.757 10 03/25/11 7:46:45.758 10 03/25/11 7:46:45.760 10 03/25/11 7:46:45.762 10 03/25/11 7:46:45.769 10 03/25/11 7:46:45.770 10 03/25/11 7:46:45.772 10 03/25/11 7:46:45.773 10 03/25/11 7:46:45.773 10 03/25/11 7:46:45.774 10 03/25/11 7:46:45.775 10 03/25/11 7:46:45.775 10 03/25/11 7:46:45.776 10 03/25/11 7:46:45.777 10 03/25/11 7:46:45.778 03 03/25/11 7:46:45.778 luup_log:1: MVRZ_DEBUG:getSetting(upload-timestamp) <0x402> luup_log:1: MVRZ_DEBUG:getSetting(upload-timestamp,0)=1301063926 <0x402> luup_log:1: MVRZ_DEBUG:Prune:cat /www/mvrz/ha_history.csv | sh /www/mvrz/pruneHistory.sh 1301063926 > /www/mvrz/ha_history.csv.out ; cp /www/mvrz/ha_his luup_log:1: MVRZ_DEBUG:mvrz_setHomeMode(0,0) mvrz_gHomeMode=0 <0x402> luup_log:1: MVRZ_DEBUG:saveFile(/www/mvrz/.mvrz_lastMode.txt, CONTENT) <0x402> Device_Variable::m_szValue_set device: 1 service: urn:upnp-org:serviceId:SwitchPower1 variable: ^[[35;1mStatus^[[0m was: 0 now: 0 #hooks: 0 upnp: 0 v:0x luup_log:1: MVRZ_DEBUG:lastPeriod is blank, resetting to 0 <0x402> luup_log:1: MVRZ_DEBUG:lastPeriod=gPeriod=0 <0x402> lua impl before 1 start pMem 0x82f000/8581120 diff: 1146880 <0x402> LuImplementation::StartLua device: 1 file:I_ModeControllerUtil.xml <0x402> ^[[31;1mMongoose XXX-mg_stop2 0x71d100 2 1^[[0m <0x400> ^[[31;1mMongoose XXX-mg_stop3 0x71d100 2 1^[[0m <0x400> ^[[31;1mMongoose XXX-mg_stop4 0x71d100 8354496 1^[[0m <0x400> JobHandler::WaitForJobsToFinish m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 abort 1 seconds 5 <0x400> JobHandler::AbortAllJobs <0x400> JobHandler::PurgeCompletedJobs force 0 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::PurgeCompletedJobs force 1 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::WaitForJobsToFinish COMPLETED OK m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 <0x400> JobHandler_LuaUPnP::~JobHandler_LuaUPnP jobs finished <0x400> ZWaveJobHandler::~ZWaveJobHandler 0x739ae0 <0x400> ZWaveJobHandler::m_bQuit_set now 1 for 0x739ae0 ~ZWaveJobHandler <0x400> ThreadedClass::m_bQuit_set now 1 for 0x739ae0 ~ZWaveJobHandler <0x400> ZWaveJobHandler::m_bQuit_set now 1 for 0x739ae0 ThreadedClass::StopThread ptr 0x739ae0 thread (nil) <0x400> ThreadedClass::m_bQuit_set now 1 for 0x739ae0 ThreadedClass::StopThread ptr 0x739ae0 thread (nil) <0x400> JobHandler::WaitForJobsToFinish m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 2 abort 1 seconds 5 <0x400> JobHandler::AbortAllJobs <0x400> JobHandler::PurgeCompletedJobs force 0 purge job size: 0 blocking 0 delete 2 <0x400> JobHandler::PurgeCompletedJobs force 1 purge job size: 0 blocking 0 delete 2 <0x400> JobHandler::PurgeCompletedJobs deleting job#0 :init (0x0x797e28) P:10 S:4 force 1 status 4 <0x400> Job::~Job finished <0x797e28> name job#0 :init (0x0x797e28) <0x400> ThreadedClass::m_bQuit_set now 1 for 0x797e28 ThreadedClass::StopThread ptr 0x797e28 thread (nil) <0x400> JobHandler::PurgeCompletedJobs deleting job#1 :getnodes (0x0x7981a8) P:10 S:4 force 1 status 4 <0x400> Job::~Job finished <0x7981a8> name job#1 :getnodes (0x0x7981a8) <0x400> ThreadedClass::m_bQuit_set now 1 for 0x7981a8 ThreadedClass::StopThread ptr 0x7981a8 thread (nil) <0x400> JobHandler::WaitForJobsToFinish COMPLETED OK m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 <0x400> ZWaveSerial::~ZWaveSerial 0x797548 <0x400> ZWaveNode::~ZWaveNode removing 1 <0x400> ZWaveNode::~ZWaveNode removing 2 <0x400> JobHandler::~JobHandler <0x400> ThreadedClass::m_bQuit_set now 1 for 0x739ae0 ~JobHandler <0x400> ThreadedClass::m_bQuit_set now 1 for 0x739ae0 ThreadedClass::StopThread ptr 0x739ae0 thread (nil) <0x400> JobHandler::WaitForJobsToFinish m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 abort 1 seconds 5 <0x400> JobHandler::AbortAllJobs <0x400> JobHandler::PurgeCompletedJobs force 0 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::PurgeCompletedJobs force 1 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::WaitForJobsToFinish COMPLETED OK m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 <0x400> ThreadedClass::m_bQuit_set now 1 for 0x739ae0 ThreadedClass::StopThread ptr 0x739ae0 thread (nil) <0x400> JobHandler_LuaUPnP::~JobHandler_LuaUPnP plugins finished <0x400> AlarmManager::~AlarmManager start 0x717488 <0x400> AlarmManager::Stop 1 <0x400> AlarmManager::~AlarmManager done 0x717488 <0x400> LuaInterface::~LuaInterface 0x798300 <0x400> UPnPService::~UPnPService 0x73d528 <0x400> UPnPService::~UPnPService 0x73d618 <0x400> UPnPService::~UPnPService 0x742068 <0x400> UPnPService::~UPnPService 0x74e018 <0x400> UPnPService::~UPnPService 0x7547f8 <0x400> UPnPService::~UPnPService 0x754950 <0x400> UPnPService::~UPnPService 0x754b88 <0x400> UPnPService::~UPnPService 0x754c48 <0x400> UPnPService::~UPnPService 0x75fcb0 <0x400> UPnPService::~UPnPService 0x75ffe0 <0x400> UPnPService::~UPnPService 0x763640 <0x400> UPnPService::~UPnPService 0x76af98 <0x400> UPnPService::~UPnPService 0x76b7f8 <0x400> UserData::CommitToDatabase skipping <0x400> JobHandler_LuaUPnP::~JobHandler_LuaUPnP finished web directory <0x400> JobHandler_LuaUPnP::~JobHandler_LuaUPnP upnp finished <0x400> JobHandler::~JobHandler <0x400> ThreadedClass::m_bQuit_set now 1 for 0x714f78 ~JobHandler <0x400> ThreadedClass::m_bQuit_set now 1 for 0x714f78 ThreadedClass::StopThread ptr 0x714f78 thread (nil) <0x400> JobHandler::WaitForJobsToFinish m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 abort 1 seconds 5 <0x400> JobHandler::AbortAllJobs <0x400> JobHandler::PurgeCompletedJobs force 0 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::PurgeCompletedJobs force 1 purge job size: 0 blocking 0 delete 0 <0x400> JobHandler::WaitForJobsToFinish COMPLETED OK m_listJob 0 m_listJob_Blocking 0 m_listJob_Delete 0 <0x400> ThreadedClass::m_bQuit_set now 1 for 0x714f78 ThreadedClass::StopThread ptr 0x714f78 thread (nil) <0x400> LuaUPNP: ending <0x400>

the MVRZ_DEBUG lines are my own LUE code debug lines.

I’m guessing that the Mongoose stop# lines are the start of it shutting down but cannot tell for sure where the real problem is. Should I be looking earlier in the log file or will the problem be immediately before the stop1 entry?

Thanks,
Neil

mcvflorin · March 25, 2011, 4:29pm

Your device has device number 1? Strange, device numbering should start from 2 because device #1 is the _Scene Controller.

What I have be noticing is that there's a sequence of "Mongoose XXX-mg_stop1..", stop2, stop3 and stop4 messages just before it terminates.

This is normal. Mongoose is the embedded web server and, while they are logged with code 1 (the error code), those aren’t errors, but debug lines to show that the startup/shutdown process reached to a certain point.

neilios · March 25, 2011, 5:00pm

When the device is added from the Developers/Create Device tab it tells me the device number is 4. This is consistent with what I had seen on my previous Vera/UI2.

What would cause a device to be totally removed from the system during run-time?

Thanks,
Neil

mcvflorin · March 25, 2011, 5:07pm

What would cause a device to be totally removed from the system during run-time?

Honestly I have no idea, but I will try to find an answer as soon as possible.

neilios · March 26, 2011, 4:22am

Found something interesting - I stopped my device initialization code from doing much to see if that was causing an issue and noticed that the argument passed to my “startup” function was device ID 1 (rather than 5 in this case) and then a little later on in the log there’s a Duplicate check and a child node removal line (look from the bottom of this log text):

09      03/25/11 21:06:11.022   JobHandler_LuaUPnP::Run device 1 ZWave room 0 type urn:schemas-micasaverde-com:device:ZWaveNetwork:1 id  parent 0 <0x400>
09      03/25/11 21:06:11.023   JobHandler_LuaUPnP::Run device 2 _Scene Controller room 0 type urn:schemas-micasaverde-com:device:SceneController:1 id 1 parent 1 <0x400>
09      03/25/11 21:06:11.024   JobHandler_LuaUPnP::Run device 3 Trane room 0 type urn:schemas-upnp-org:device:HVAC_ZoneThermostat:1 id 2 parent 1 <0x400>
09      03/25/11 21:06:11.024   JobHandler_LuaUPnP::Run device 4 2GIG room 0 type urn:schemas-upnp-org:device:HVAC_ZoneThermostat:1 id 3 parent 1 <0x400>
09      03/25/11 21:06:11.025   JobHandler_LuaUPnP::Run device 5 Is Occupied room 1 type urn:schemas-upnp-org:device:BinaryLight:1 id 99 parent 1 <0x400>
02      03/25/11 21:06:11.035   GetSunriseSunset error#1 sunrise: 1301032967/2011-03-24 23:02:47 sunset: 1301076558/2011-03-25 11:09:18 midnight: 1301036400/7619300 <0x400>
02      03/25/11 21:06:11.047   GetSunriseSunset error#1 sunrise: 1301119349/2011-03-25 23:02:29 sunset: 1301162940/2011-03-26 11:09:00 midnight: 1301122800/7524108 <0x400>
03      03/25/11 21:06:11.054   JobHandler_LuaUPnP::Run ready 5 devices installation 16797 missing  version *1.1.992* pMem 0x82f000/8581120 diff: 1146880 <0x400>
02      03/25/11 21:06:11.283   ZWaveNode::UpdateCommandClasses node 1 empty -- -- <0xc04>
06      03/25/11 21:06:11.288   Device_Variable::m_szValue_set device: 3 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: 1 now: 1 #hooks: 0 upnp: 0 v:0x792ed8/NONE duplicate:1 <0xc04>
06      03/25/11 21:06:11.289   Device_Variable::m_szValue_set device: 3 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: 1 now: 1 #hooks: 0 upnp: 0 v:0x792ed8/NONE duplicate:1 <0xc04>
06      03/25/11 21:06:11.292   Device_Variable::m_szValue_set device: 4 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: 1 now: 1 #hooks: 0 upnp: 0 v:0x792ed8/NONE duplicate:1 <0xc04>
06      03/25/11 21:06:11.293   Device_Variable::m_szValue_set device: 4 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: 1 now: 1 #hooks: 0 upnp: 0 v:0x792ed8/NONE duplicate:1 <0xc04>
09      03/25/11 21:06:11.294   ZWaveJobHandler::UpdateNodes dongle is node 1 device 2 iExtraNodesForTest 0 <0xc04>
06      03/25/11 21:06:11.295   Device_Variable::m_szValue_set device: 2 service: urn:micasaverde-com:serviceId:SceneController1 variable: Scenes was:  now:  #hooks: 0 upnp: 0 v:0x792d48/NONE duplicate:1 <0xc04>
09      03/25/11 21:06:11.297   ZWaveJobHandler::UpdateNodes node 2 Device_NumTemplate D_HVAC_ZoneThermostat1.xml type ZWaveThermostat Device_Num 3 Room 0 auto-conf -1 cap 0xd2 sec 0x9c res 0x0 bas 0x4 gen 0x8 spe 0x6 config 1 secure 0 Trane classes 31,40,42,43,44,45,70,72,76,81,85,86, mfr: / mfr: 0x8b prodtype: 0x5452 prodid: 0x5431 <0xc04>
09      03/25/11 21:06:11.298   ZWaveJobHandler::UpdateNodes node 3 Device_NumTemplate D_HVAC_ZoneThermostat1.xml type ZWaveThermostat Device_Num 4 Room 0 auto-conf -1 cap 0x52 sec 0x5c res 0x0 bas 0x4 gen 0x8 spe 0x6 config 1 secure 0 2GIG classes 20,31,40,42,43,44,72,86,87, mfr: / mfr: 0x98 prodtype: 0x1 prodid: 0x1e <0xc04>
06      03/25/11 21:06:11.299   Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusID was: 7 now: 5 #hooks: 0 upnp: 0 v:0x77b7e0/NONE duplicate:0 <0xc04>
06      03/25/11 21:06:11.300   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364021 subs: 0 <0xc04>
06      03/25/11 21:06:11.301   Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: Starting ZWave Network now: Configuring ZWave devices #hooks: 0 upnp: 0 v:0x77b508/NONE duplicate:0 <0xc04>
06      03/25/11 21:06:11.302   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364022 subs: 0 <0xc04>
06      03/25/11 21:06:11.303   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364023 subs: 0 <0xc04>
06      03/25/11 21:06:11.305   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364024 subs: 0 <0xc04>
04      03/25/11 21:06:11.307   <Job ID="1" Name="getnodes" Created="11-03-25 21:06:10" Started="11-03-25 21:06:10" Completed="11-03-25 21:06:11" Duration="0.735038000" Runtime="0.725435000" Status="Successful" LastNote=""/> <0xc04>
06      03/25/11 21:06:13.010   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364025 subs: 0 <0x402>
06      03/25/11 21:06:13.016   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364026 subs: 0 <0x402>
50      03/25/11 21:06:13.018   luup_log:1: Initializing MVRZ Device, ID=1 <0x402>
50      03/25/11 21:06:13.019   luup_log:1: ...skipping initialization <0x402>
06      03/25/11 21:06:14.015   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364027 subs: 0 <0x803>
06      03/25/11 21:06:14.016   UserData::m_luc_HAG_Variables_set variable: DataVersionUserData now: 112364002 subs: 0 <0x803>
06      03/25/11 21:06:14.017   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364028 subs: 0 <0x803>
06      03/25/11 21:06:14.018   UserData::m_luc_HAG_Variables_set variable: DataVersionUserData now: 112364003 subs: 0 <0x803>
06      03/25/11 21:06:14.018   Device_Variable::m_szValue_set device: 3 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: ManufacturerInfo was: 139,21586,21553 now: 139,21586,21553 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06      03/25/11 21:06:14.019   Device_Variable::m_szValue_set device: 3 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: Capabilities was: 210,156,0,4,8,6|49,64,66,67,68,69,112,114,118,129,133,134, now: 210,156,0,4,8,6|49,64,66,67,68,69,112,114,118,129,133,134, #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06      03/25/11 21:06:14.020   Device_Variable::m_szValue_set device: 3 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: Neighbors was: 1, now: 1, #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06      03/25/11 21:06:14.022   UserData::m_luc_HAG_Variables_set variable: DataVersionStatus now: 112364029 subs: 0 <0x803>
06      03/25/11 21:06:14.022   UserData::m_luc_HAG_Variables_set variable: DataVersionUserData now: 112364004 subs: 0 <0x803>
06      03/25/11 21:06:14.023   Device_Variable::m_szValue_set device: 4 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: ManufacturerInfo was: 152,1,30 now: 152,1,30 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06      03/25/11 21:06:14.024   Device_Variable::m_szValue_set device: 4 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: Capabilities was: 82,92,0,4,8,6|32,49,64,66,67,68,114,134,135, now: 82,92,0,4,8,6|32,49,64,66,67,68,114,134,135, #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06      03/25/11 21:06:14.025   Device_Variable::m_szValue_set device: 4 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: Neighbors was: 1,2, now: 1,2, #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
09      03/25/11 21:06:14.026   Child_Devices::Synchronize removing 5 from parent 1 <0x803>

… so this is where my manually added device is being removed.

If the system is meant to still pass in the device ID on my device to the function, then it thinks it set to 1, and therefore a duplicate device ID.

So, why does it think the device ID is now 1. At the top of the log file, you’ll see the enumeration of all 5 devices (my new one is #5), so it’s got the right ID early on.

Is there something I’m missing here - it’s beginning to look like a bug to me.

Thanks,
Neil

neilios · March 28, 2011, 7:55am

Can anyone verify if this is a Vera bug or if I need to to something different in setting up my device?

Thanks,
Neil

mcvflorin · March 28, 2011, 12:43pm

What is selected in the Controlled via drop-down list, in the Advanced tab? Try to set it to No parent/Please select.

neilios · March 29, 2011, 4:30am

YES!!!

Thank you - that did it! I could have sworn that when I’d tried that before I got a message telling me to set it so have just done so each time.

Kind Regards,
Neil