Log from above/below issue ([url=http://forum.micasaverde.com/index.php/topic,13779.0.html]http://forum.micasaverde.com/index.php/topic,13779.0.html[/url])
08 02/22/13 8:56:26.804 JobHandler_LuaUPnP::HandleActionRequest device: 17 service: urn:upnp-org:serviceId:Dimming1 action: SetLoadLevelTarget <0x327b2680>
08 02/22/13 8:56:26.804 JobHandler_LuaUPnP::HandleActionRequest argument fwdserver=1 <0x327b2680>
08 02/22/13 8:56:26.804 JobHandler_LuaUPnP::HandleActionRequest argument remoteIP=24.60.136.225 <0x327b2680>
08 02/22/13 8:56:26.804 JobHandler_LuaUPnP::HandleActionRequest argument token=b1cf028757ce01d859d34aeca1459515bf0e79c5 <0x327b2680>
08 02/22/13 8:56:26.805 JobHandler_LuaUPnP::HandleActionRequest argument PK_AccessPoint=30004107 <0x327b2680>
08 02/22/13 8:56:26.805 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=17 <0x327b2680>
08 02/22/13 8:56:26.808 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:upnp-org:serviceId:Dimming1 <0x327b2680>
08 02/22/13 8:56:26.808 JobHandler_LuaUPnP::HandleActionRequest argument action=SetLoadLevelTarget <0x327b2680>
08 02/22/13 8:56:26.809 JobHandler_LuaUPnP::HandleActionRequest argument newLoadlevelTarget=100 <0x327b2680>
08 02/22/13 8:56:26.809 JobHandler_LuaUPnP::HandleActionRequest argument rand=0.9907635049894452 <0x327b2680>
06 02/22/13 8:56:26.809 Device_Variable::m_szValue_set device: 17 service: urn:upnp-org:serviceId:Dimming1 variable: LoadLevelTarget was: 0 now: 100 #hooks: 0 upnp: 0 v:0xfec1d0/NONE duplicate:0 <0x327b2680>
02 02/22/13 8:56:26.917 ZWJob_SendData UPDATE MANUAL ROUTE 6=0xfa12c7 <0x327b2680>
02 02/22/13 8:56:26.923 UPDATE MANUAL ROUTE2 6=0xfa12c7 <0x2b971680>
02 02/22/13 8:56:26.924 ZW_Send_Data node 6 USING ROUTE 255.168.13.250 <0x2b971680>
02 02/22/13 8:56:27.027 UPDATE MANUAL ROUTE2 6=0xfa12c7 <0x2b971680>
02 02/22/13 8:56:27.028 ZW_Send_Data node 6 USING ROUTE 255.168.13.250 <0x2b971680>
06 02/22/13 8:56:27.136 Device_Variable::m_szValue_set device: 17 service: urn:upnp-org:serviceId:Dimming1 variable: LoadLevelStatus was: 0 now: 100 #hooks: 7 upnp: 0 v:0xcd2448/NONE duplicate:0 <0x2b571680>
06 02/22/13 8:56:27.136 Device_Variable::m_szValue_set device: 17 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 0 now: 0 #hooks: 0 upnp: 0 v:0xfc9450/NONE duplicate:1 <0x2b571680>
50 02/22/13 8:56:27.137 luup_log:0: w_dimlevl(17/urn:upnp-org:serviceId:Dimming1/LoadLevelStatus/0/100) <0x2b571680>
06 02/22/13 8:56:27.137 Device_Variable::m_szValue_set device: 17 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 0 now: 600 #hooks: 0 upnp: 0 v:0xfc9450/NONE duplicate:0 <0x2b571680>
06 02/22/13 8:56:27.138 Device_Variable::m_szValue_set device: 17 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 0,480,480,1361539912,113 now: 600,34,600,1361541387,1588 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b571680>
50 02/22/13 8:56:27.139 luup_log:0: w_watts(17/urn:upnp-org:serviceId:Dimming1/LoadLevelStatus/0/600) <0x2b571680>
50 02/22/13 8:56:27.139 luup_log:0: watts(17):[0,600] <0x2b571680>
06 02/22/13 8:56:27.139 Device_Variable::m_szValue_set device: 17 service: urn:upnp-org:serviceId:SwitchPower1 variable: Status was: 0 now: 1 #hooks: 4 upnp: 0 v:0xfb33b0/NONE duplicate:0 <0x2b571680>
08 02/22/13 8:56:27.140 JobHandler_LuaUPnP::HandleActionRequest device: 198 service: urn:rts-services-com:serviceId:ProgramLogicC action: TriggerAction <0x2b571680>
08 02/22/13 8:56:27.140 JobHandler_LuaUPnP::HandleActionRequest argument triggerName=LightOn_CeilingBM <0x2b571680>
50 02/22/13 8:56:27.141 luup_log:198: ProgramLogicEG:198:TriggerAction:LightOn_CeilingBM <0x2b571680>
06 02/22/13 8:56:27.141 Device_Variable::m_szValue_set device: 198 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: None now: #hooks: 3 upnp: 0 v:0x1154700/NONE duplicate:0 <0x2b571680>
07 02/22/13 8:56:27.142 Event::Evaluate 72 LightsOff_Basement1 scene ProgramLogic_201 is false repeat 0/-1 <0x2b571680>
07 02/22/13 8:56:27.142 Event::Evaluate 75 LightOn_1st scene ProgramLogic_201 is false repeat 0/-1 <0x2b571680>
07 02/22/13 8:56:27.142 Event::Evaluate 77 LightOn_Basement1 scene ProgramLogic_201 is false repeat 0/-1 <0x2b571680>
06 02/22/13 8:56:27.143 Device_Variable::m_szValue_set device: 198 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1361539953 now: 1361541387 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b571680>
50 02/22/13 8:56:27.143 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOn_CeilingBM State:true Device:17 <0x2b571680>
50 02/22/13 8:56:27.144 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOff_CeilingBM State:false Device:17 <0x2b571680>
50 02/22/13 8:56:27.144 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOn_HallwayBM State:false Device:92 <0x2b571680>
50 02/22/13 8:56:27.145 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOff_HallwayBM State:true Device:92 <0x2b571680>
50 02/22/13 8:56:27.155 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOn_StairsBM State:false Device:91 <0x2b571680>
50 02/22/13 8:56:27.157 luup_log:198: ProgramLogicEG:198:EvalTriggers:Name:LightOff_StairsBM State:true Device:91 <0x2b571680>
50 02/22/13 8:56:27.159 luup_log:198: ProgramLogicEG:198:EvalConditions:Condition:AllLightsOff_Basement Trigger:LightOn_CeilingBM Result:false <0x2b571680>
50 02/22/13 8:56:27.160 luup_log:198: ProgramLogicEG:198:EvalConditions:Condition:LightOn_Basement Trigger:LightOn_CeilingBM Result:true <0x2b571680>
50 02/22/13 8:56:27.160 luup_log:198: ProgramLogicEG:198:TriggerCondition:LightOn_Basement <0x2b571680>
06 02/22/13 8:56:27.161 Device_Variable::m_szValue_set device: 198 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: now: LightOn_Basement #hooks: 3 upnp: 0 v:0x1154700/NONE duplicate:0 <0x2b571680>
07 02/22/13 8:56:27.161 Event::Evaluate 72 LightsOff_Basement1 scene ProgramLogic_201 is false repeat 0/-1 <0x2b571680>
07 02/22/13 8:56:27.161 Event::Evaluate 75 LightOn_1st scene ProgramLogic_201 is false repeat 0/-1 <0x2b571680>
08 02/22/13 8:56:27.162 JobHandler_LuaUPnP::HandleActionRequest device: 201 service: urn:rts-services-com:serviceId:ProgramLogicC action: TriggerAction <0x2b571680>
08 02/22/13 8:56:27.162 JobHandler_LuaUPnP::HandleActionRequest argument triggerName=LightOn_Basement1 <0x2b571680>
50 02/22/13 8:56:27.163 luup_log:201: ProgramLogicEG:201:TriggerAction:LightOn_Basement1 <0x2b571680>
06 02/22/13 8:56:27.163 Device_Variable::m_szValue_set device: 201 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: None now: #hooks: 0 upnp: 0 v:0x1154700/NONE duplicate:0 <0x2b571680>
06 02/22/13 8:56:27.164 Device_Variable::m_szValue_set device: 201 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1361539954 now: 1361541387 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b571680>
50 02/22/13 8:56:27.164 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightsOff_Basement1 State:true Device:198 <0x2b571680>
50 02/22/13 8:56:27.165 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightsOff_1st State:true Device:200 <0x2b571680>
50 02/22/13 8:56:27.165 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightsOff_2nd State:true Device:199 <0x2b571680>
50 02/22/13 8:56:27.166 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightOn_1st State:true Device:198 <0x2b571680>
50 02/22/13 8:56:27.166 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightOn_2nd State:true Device:199 <0x2b571680>
50 02/22/13 8:56:27.167 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:LightOn_Basement1 State:true Device:198 <0x2b571680>
50 02/22/13 8:56:27.167 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:peopleInTheHouse State:true Device:188 <0x2b571680>
50 02/22/13 8:56:27.168 luup_log:201: ProgramLogicEG:201:EvalTriggers:Name:isWinter State:true Device:35 <0x2b571680>
50 02/22/13 8:56:27.169 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:noHeat_Basement Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.170 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:noHeat_1st Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.172 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:noHeat_2nd Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.173 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:heat_Basement Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.173 luup_log:201: ProgramLogicEG:201:TriggerCondition:heat_Basement <0x2b571680>
06 02/22/13 8:56:27.174 Device_Variable::m_szValue_set device: 201 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: now: heat_Basement #hooks: 0 upnp: 0 v:0x1154700/NONE duplicate:0 <0x2b571680>
06 02/22/13 8:56:27.174 Device_Variable::m_szValue_set device: 201 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1361541387 now: 1361541387 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2b571680>
50 02/22/13 8:56:27.176 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:heat_1st Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.178 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:heat_2nd Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.179 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:arePresent Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.179 luup_log:201: ProgramLogicEG:201:EvalConditions:Condition:Winter Trigger:LightOn_Basement1 Result:true <0x2b571680>
50 02/22/13 8:56:27.180 luup_log:201: ProgramLogicEG:201:SaveDB:{{name ='noHeat_Basement', state = true, seq = 1361539912},{name ='noHeat_1st', state = true, seq = 1361539586},{name ='noHeat_2nd', state = true, seq = 1361539738},{name ='heat_Basement', state = true, seq = 1361541387},{name ='heat_1st', state = true, seq = 0},{name ='heat_2nd', state = true, seq = 1361530444},{name ='LightsOff_Basement1', state = true, seq = 1361539912},{name ='LightsOff_1st', state = true, seq = 1361539586},{name ='LightsOff_2nd', state = true, seq = 1361539738},{name ='LightOn_1st', state = true, seq = 0},{name ='LightOn_2nd', state = true, seq = 1361530444},{name ='LightOn_Basement1', state = true, seq = 1361541387},{name ='peopleInTheHouse', state = true, seq = 0},{name ='isWinter', state = true, seq = 0},{name ='arePresent', state = true, seq = 0},{name ='Winter', state = true, seq = 0},} <0x2b571680>
06 02/22/13 8:56:27.181 Device_Variable::m_szValue_set device: 201 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name ='noHeat_Basement', state = true, seq = 1361539912},{name ='noHeat_1st', state = true, seq = 1361539586},{name ='noHeat_2nd', state = true, seq = 1361539738},{name ='heat_Basement', state = true, seq = 1361534354},{name ='heat_1st', state = true, seq = 0},{name ='heat_2nd', state = true, seq = 1361530444},{name ='LightsOff_Basement1', state = true, seq = 1361539912},{name ='LightsOff_1st', state = true, seq = 1361539586},{name ='LightsOff_2nd', state = true, seq = 1361539738},{name ='LightOn_1st', state = true, seq = 0},{name ='LightOn_2nd', state = true, seq = 1361530444},{name ='LightOn_Basement1', state = true, seq = 1361534354},{name ='peopleInTheHouse', state = true, seq = 0},{name ='isWinter', state = true, seq = 0},{name ='arePresent', state = true, seq = 0},{name ='Winter', state = true, seq = 0},} now: {{name ='noHeat_Basement', state = true, seq = 1361539912},{name ='noHeat_1st', state = true, seq = 1361539586},{name ='noHeat_2nd', state = true, seq = 1361539738},{name ='heat_Basement', state = true, seq = 1361541387},{name ='heat_1st', state = true, seq = 0},{name ='heat_2nd', state = true, seq = 1361530444},{name ='LightsOff_Basement1', state = true, seq = 1361539912},{name ='LightsOff_1st', state = true, seq = 1361539586},{name ='LightsOff_2nd', state = true, seq = 1361539738},{name ='LightOn_1st', state = true, seq = 0},{name ='LightOn_2nd', state = true, seq = 1361530444},{name ='LightOn_Basement1', state = true, seq = 1361541387},{name ='peopleInTheHouse', state = true, seq = 0},{name ='isWinter', state = true, seq = 0},{name ='arePresent', state = true, seq = 0},{name ='Winter', state = true, seq = 0},} #hooks: 0 upnp: 0 v:0x1165e58/NONE duplicate:0 <0x2b571680>
07 02/22/13 8:56:27.182 Event::Evaluate 77 LightOn_Basement1 scene ProgramLogic_201 is true users:(null) allow:1 <0x2b571680>
08 02/22/13 8:56:27.182 Scene::RunScene running 188 ProgramLogic_201 <0x2b571680>
06 02/22/13 8:56:27.183 Device_Variable::m_szValue_set device: 198 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1361541387 now: 1361541387 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2b571680>
50 02/22/13 8:56:27.183 luup_log:198: ProgramLogicEG:198:FireAction:Nothing found for:LightOn_Basement <0x2b571680>
50 02/22/13 8:56:27.184 luup_log:198: ProgramLogicEG:198:SaveDB:{{name ='AllLightsOff_Basement', state = false, seq = 1361539912},{name ='LightOn_Basement', state = true, seq = 1361541387},{name ='LightOn_CeilingBM', state = true, seq = 1361541387},{name ='LightOff_CeilingBM', state = false, seq = 1361539912},{name ='LightOn_HallwayBM', state = false, seq = 1361533592},{name ='LightOff_HallwayBM', state = true, seq = 1361539912},{name ='LightOn_StairsBM', state = false, seq = 1361530280},{name ='LightOff_StairsBM', state = true, seq = 1361539917},} <0x2b571680>
06 02/22/13 8:56:27.184 Device_Variable::m_szValue_set device: 198 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name ='AllLightsOff_Basement', state = true, seq = 1361539912},{name ='LightOn_Basement', state = false, seq = 1361534354},{name ='LightOn_CeilingBM', state = false, seq = 1361534354},{name ='LightOff_CeilingBM', state = true, seq = 1361539912},{name ='LightOn_HallwayBM', state = false, seq = 1361533592},{name ='LightOff_HallwayBM', state = true, seq = 1361539912},{name ='LightOn_StairsBM', state = false, seq = 1361530280},{name ='LightOff_StairsBM', state = true, seq = 1361539917},} now: {{name ='AllLightsOff_Basement', state = false, seq = 1361539912},{name ='LightOn_Basement', state = true, seq = 1361541387},{name ='LightOn_CeilingBM', state = true, seq = 1361541387},{name ='LightOff_CeilingBM', state = false, seq = 1361539912},{name ='LightOn_HallwayBM', state = false, seq = 1361533592},{name ='LightOff_HallwayBM', state = true, seq = 1361539912},{name ='LightOn_StairsBM', state = false, seq = 1361530280},{name ='LightOff_StairsBM', state = true, seq = 1361539917},} #hooks: 0 upnp: 0 v:0x1165e58/NONE duplicate:0 <0x2b571680>
07 02/22/13 8:56:27.186 Event::Evaluate 36 LightOn_CeilingBM scene ProgramLogic_198 is true users:(null) allow:1 <0x2b571680>
08 02/22/13 8:56:27.186 Scene::RunScene running 185 ProgramLogic_198 <0x2b571680>
07 02/22/13 8:56:27.186 Event::Evaluate 37 LightOff_CeilingBM scene ProgramLogic_198 is false repeat 0/-1 <0x2b571680>
06 02/22/13 8:56:27.186 Device_Variable::m_szValue_set device: 17 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 600 now: 600 #hooks: 0 upnp: 0 v:0xfc9450/NONE duplicate:1 <0x2b571680>
50 02/22/13 8:56:27.189 luup_log:0: w_switch(17/urn:upnp-org:serviceId:SwitchPower1/Status/0/1) <0x2b571680>
06 02/22/13 8:56:27.190 Device_Variable::m_szValue_set device: 17 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 600 now: 600 #hooks: 0 upnp: 0 v:0xfc9450/NONE duplicate:1 <0x2b571680>
50 02/22/13 8:56:27.190 luup_log:0: w_watts(17/urn:upnp-org:serviceId:SwitchPower1/Status/0/600) <0x2b571680>
50 02/22/13 8:56:27.191 luup_log:0: watts(17):[0,600] <0x2b571680>
04 02/22/13 8:56:27.192 <Job ID="260" Name="Level 99 node 6" Device="17" Created="2013-02-22 8:56:26" Started="2013-02-22 8:56:26" Completed="2013-02-22 8:56:27" Duration="0.274787000" Runtime="0.268610000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveDimmableLight" NodeDescription="Lights_Ceiling_Basement"/> <0x2b571680>
Edit: Added link to post this log applies to.