startSensor(290,275) <0x77eae320>
50	03/24/19 12:40:44.308	luup_log:275: Reactor(debug): loadSensorConfig(290) <0x77eae320>
50	03/24/19 12:40:44.328	luup_log:275: Reactor(debug): loadSensorConfig() loaded configuration version 19051 <0x77eae320>
50	03/24/19 12:40:44.329	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:40:44.339	luup_log:275: Reactor(debug): loadCleanState() deleting saved state "lastUsed" <0x77eae320>
50	03/24/19 12:40:44.351	luup_log:275: Reactor(debug): loadCleanState() saving updated state { condb3bbob2={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, condb3bb0iv={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, root={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="root", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, condb33h5b2={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, grpb33dm2i={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, cond0={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 }, grpb33c7p3={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, condb33fwzm={ statestamp=1553445621(03/24/19.12:40:21), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445621(03/24/19.12:40:21), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445621(03/24/19.12:40:21), laststate=false }, grpb3baia5={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553445615(03/24/19.12:40:15) }, id="grpb3baia5", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false } } <0x77eae320>
5621, "laststate": false }, "lastUsed": 1553445644, "grpb3baia5": { "statestamp": 1553445615, "stateedge": { "1": 1553444192, "0": 1553445615 }, "id": "grpb3baia5", "evalstamp": 1553445615, "lastvalue": false, "evalstate": false, "priorvalue": true, "valuestamp": 1553445615, "laststate": false } } #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x77eae320>
50	03/24/19 12:40:44.358	luup_log:275: Reactor(debug): loadCleanState() returning restored cstate <0x77eae320>
50	03/24/19 12:40:44.360	luup_log:275: Reactor(debug): scheduleTick({ id="290", owner=290, func=function: 0x1f3c090 },1,{ replace=true }) <0x77eae320>
50	03/24/19 12:40:44.361	luup_log:275: Reactor(debug): scheduleTick() rescheduling plugin tick for 1s to 1 <0x77eae320>
04	03/24/19 12:40:44.362	<Job ID="504" Name="" Device="290" Created="2019-03-24 12:40:44" Started="2019-03-24 12:40:44" Completed="2019-03-24 12:40:44" Duration="0.57780000" Runtime="0.57290000" Status="Successful" LastNote=""/> <0x77eae320>
50	03/24/19 12:40:45.101	luup_log:275: Reactor(debug): tick("13") pluginDevice=275 <0x7241e520>
50	03/24/19 12:40:45.101	luup_log:275: Reactor(debug): tick() inserting eligible task "290" when 1 now 1553445645(03/24/19.12:40:45) <0x7241e520>
50	03/24/19 12:40:45.102	luup_log:275: Reactor(debug): tick() to-do list is { 1={ info="", args={  }, id="290", owner=290, func=function: 0x1f3c090 } } <0x7241e520>
50	03/24/19 12:40:45.103	luup_log:275: Reactor(debug): tick() calling "function: 0x1f3c090"(290,"290") for 290 (task "TV Tuner Routine" "function: 0x1f3c090") <0x7241e520>
50	03/24/19 12:40:45.103	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x7241e520>
50	03/24/19 12:40:45.103	luup_log:275: Reactor(debug): loadCleanState(290) <0x7241e520>
50	03/24/19 12:40:45.104	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x7241e520>
50	03/24/19 12:40:45.105	luup_log:275: Reactor(debug): updateSensor() base time is 1553445645(03/24/19.12:40:45) ({ hour=12, min=40, wday=1, day=24, month=3, year=2019, sec=45, yday=83, isdst=true }) <0x7241e520>
50	03/24/19 12:40:45.106	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x7241e520>
50	03/24/19 12:40:45.106	luup_log:275: Reactor(debug): loadCleanState(290) <0x7241e520>
50	03/24/19 12:40:45.106	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x7241e520>
50	03/24/19 12:40:45.107	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x7241e520>
50	03/24/19 12:40:45.107	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x7241e520>
50	03/24/19 12:40:45.107	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x7241e520>
50	03/24/19 12:40:45.108	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine" } <0x7241e520>
50	03/24/19 12:40:45.108	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.109	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.109	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x7241e520>
50	03/24/19 12:40:45.109	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x7241e520>
50	03/24/19 12:40:45.111	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ variable="Status2", type="service", value="1", id="condb33h5b2", device=262, service="urn:dcineco-com:serviceId:MSwitch1", devicename="MultiSwitch Global", operator="=" } }, operator="and" } }, operator="and" } <0x7241e520>
50	03/24/19 12:40:45.111	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.112	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.112	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.113	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ variable="Status2", type="service", value="1", id="condb33h5b2", device=262, service="urn:dcineco-com:serviceId:MSwitch1", devicename="MultiSwitch Global", operator="=" } }, operator="and" } <0x7241e520>
50	03/24/19 12:40:45.113	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.114	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.114	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.115	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x7241e520>
50	03/24/19 12:40:45.115	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.116	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.116	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x7241e520>
50	03/24/19 12:40:45.116	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "0""=""1"? <0x7241e520>
50	03/24/19 12:40:45.117	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state false timer nil <0x7241e520>
50	03/24/19 12:40:45.117	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.118	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { variable="Status2", type="service", value="1", id="condb33h5b2", device=262, service="urn:dcineco-com:serviceId:MSwitch1", devicename="MultiSwitch Global", operator="=" } <0x7241e520>
50	03/24/19 12:40:45.118	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.118	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.119	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x7241e520>
50	03/24/19 12:40:45.119	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "0""=""1"? <0x7241e520>
50	03/24/19 12:40:45.120	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state false timer nil <0x7241e520>
50	03/24/19 12:40:45.120	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.120	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state false timer false <0x7241e520>
50	03/24/19 12:40:45.121	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.121	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state false timer false <0x7241e520>
50	03/24/19 12:40:45.122	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33c7p3" "group" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.123	luup_log:275: Reactor(debug): evaluateGroup() process #3 in "root": { type="group", name="Main TV Ping On", id="grpb3baia5", conditions={ 1={ type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 }, 2={ type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } }, operator="and" } <0x7241e520>
50	03/24/19 12:40:45.124	luup_log:275: Reactor(debug): processCondition("grpb3baia5","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.124	luup_log:275: Reactor(debug): evaluateCondition("grpb3baia5","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.125	luup_log:275: Reactor(debug): evaluateGroup("grpb3baia5","root",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.125	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb3baia5": { type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 } <0x7241e520>
50	03/24/19 12:40:45.126	luup_log:275: Reactor(debug): processCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.126	luup_log:275: Reactor(debug): evaluateCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.126	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x7241e520>
50	03/24/19 12:40:45.127	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:upnp-org:serviceId:SwitchPower1"/"Status" "1""=""1"? <0x7241e520>
50	03/24/19 12:40:45.127	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bb0iv", new value="1" <0x7241e520>
50	03/24/19 12:40:45.128	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bb0iv" result is state true timer nil <0x7241e520>
50	03/24/19 12:40:45.128	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bb0iv" "service" final true, group now true <0x7241e520>
50	03/24/19 12:40:45.129	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb3baia5": { type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } <0x7241e520>
50	03/24/19 12:40:45.129	luup_log:275: Reactor(debug): processCondition("condb3bbob2","grpb3baia5",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.129	luup_log:275: Reactor(debug): evaluateCondition("condb3bbob2","grpb3baia5",cdata,290) <0x7241e520>
50	03/24/19 12:40:45.130	luup_log:275: Reactor(debug): getValue("",nil,290) <0x7241e520>
50	03/24/19 12:40:45.130	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:toggledbits-com:serviceId:ReactorGroup"/"GroupStatus_grpb33c7p3" "0""istrue"""? <0x7241e520>
50	03/24/19 12:40:45.131	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bbob2" result is state false timer nil <0x7241e520>
50	03/24/19 12:40:45.131	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bbob2" "service" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.132	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb3baia5" result is state false timer false <0x7241e520>
50	03/24/19 12:40:45.132	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb3baia5" "group" final false, group now false <0x7241e520>
50	03/24/19 12:40:45.133	luup_log:275: Reactor(debug): processCondition() eval group nil cond "root" result is state false timer false <0x7241e520>
50	03/24/19 12:40:45.134	luup_log:275: Reactor(debug): updateSensor() trip ""was false now false, retrig false <0x7241e520>
50	03/24/19 12:40:45.135	luup_log:275: Reactor(debug): updateSensor() trouble false <0x7241e520>
44192, "0": 1553445615 }, "id": "grpb3baia5", "evalstamp": 1553445615, "lastvalue": false, "evalstate": false, "priorvalue": true, "valuestamp": 1553445615, "laststate": false } } #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x7241e520>
50	03/24/19 12:40:45.142	luup_log:275: Reactor(debug): tick() successful return from "function: 0x1f3c090"(290) <0x7241e520>
50	03/24/19 12:40:45.142	luup_log:275: Reactor(debug): tick() next eligible task scheduled for "03/24/19 12:41:00" <0x7241e520>
50	03/24/19 12:40:45.143	luup_log:275: Reactor(debug): tick() scheduling next tick("13") for 15 (1553445660(03/24/19.12:41:00)) <0x7241e520>
01	03/24/19 12:40:48.134	LuaInterface::CallFunction_Timer device 212 refreshCache took 10 seconds <0x7461e520>
01	03/24/19 12:40:55.446	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:40:55.546	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
01	03/24/19 12:40:55.646	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:40:55.746	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
50	03/24/19 12:41:00.101	luup_log:275: Reactor(debug): tick("12") pluginDevice=275 <0x7241e520>
50	03/24/19 12:41:00.101	luup_log:275: Reactor(debug): tick() stamp mismatch (got 12, expecting 13), newer thread running. Bye! <0x7241e520>
50	03/24/19 12:41:00.102	luup_log:275: Reactor(debug): tick("13") pluginDevice=275 <0x7241e520>
50	03/24/19 12:41:00.103	luup_log:275: Reactor(debug): tick() inserting eligible task "275" when 1553445660(03/24/19.12:41:00) now 1553445660(03/24/19.12:41:00) <0x7241e520>
50	03/24/19 12:41:00.103	luup_log:275: Reactor(debug): tick() to-do list is { 1={ info="", args={  }, id="275", owner=275, func=function: 0x1f3c318 } } <0x7241e520>
50	03/24/19 12:41:00.104	luup_log:275: Reactor(debug): tick() calling "masterTick"(275,"275") for 275 (task "Reactor" "masterTick") <0x7241e520>
50	03/24/19 12:41:00.104	luup_log:275: Reactor(debug): masterTick(275) <0x7241e520>
50	03/24/19 12:41:00.105	luup_log:275: Reactor(debug): scheduleTick("275",1553445720(03/24/19.12:42:00),nil) <0x7241e520>
50	03/24/19 12:41:00.105	luup_log:275: Reactor(debug): masterTick() current DST "1", last "1" <0x7241e520>
50	03/24/19 12:41:00.106	luup_log:275: Reactor(debug): tick() successful return from "masterTick"(275) <0x7241e520>
50	03/24/19 12:41:00.107	luup_log:275: Reactor(debug): tick() next eligible task scheduled for "03/24/19 12:42:00" <0x7241e520>
50	03/24/19 12:41:00.107	luup_log:275: Reactor(debug): tick() scheduling next tick("13") for 60 (1553445720(03/24/19.12:42:00)) <0x7241e520>
01	03/24/19 12:41:07.171	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:07.249	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:07.329	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:07.410	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
02	03/24/19 12:41:14.358	15622 updated ip 192.168.255.125 mac f0:bf:97:aa:ab:a1 <0x709dd520>
02	03/24/19 12:41:14.359	15622 ip 192.168.255.125 returned mac f0:bf:97:aa:ab:a1 device 0xec6940 <0x709dd520>
02	03/24/19 12:41:14.359	15622 before test device 0xec6940 mac -f0:bf:97:aa:ab:a1- empty 0 <0x709dd520>
01	03/24/19 12:41:14.375	FileUtils::ReadURL 0/resp:404 user: pass: size 1 http://192.168.255.125:23519/Ircc.xml//IRCCSCPD.xml response:  <0x709dd520>
01	03/24/19 12:41:14.376	JobHandler_LuaUPnP::ConfirmUpnpFile can't retrieve http://192.168.255.125:23519/Ircc.xml//IRCCSCPD.xml basefile IRCCSCPD.xml <0x709dd520>
04	03/24/19 12:41:14.962	<Job ID="505" Name="pollnode #9 1 cmds" Device="7" Created="2019-03-24 12:41:14" Started="2019-03-24 12:41:14" Completed="2019-03-24 12:41:14" Duration="0.125503000" Runtime="0.123257000" Status="Successful" LastNote="" Node="9" NodeType="ZWaveDimmableLight" NodeDescription="Wall Spots"/> <0x7721e520>
02	03/24/19 12:41:14.963	Device_Basic::AddPoll 7 poll list full, deleting old one <0x7721e520>
01	03/24/19 12:41:17.964	LuaInterface::CallFunction_Timer device 273 refreshCache took 10 seconds <0x7261e520>
01	03/24/19 12:41:25.372	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:41:25.472	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
01	03/24/19 12:41:25.572	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:41:25.672	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
01	03/24/19 12:41:37.095	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:37.169	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:37.248	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:41:37.315	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
04	03/24/19 12:41:44.229	<Job ID="506" Name="pollnode #10 1 cmds" Device="8" Created="2019-03-24 12:41:44" Started="2019-03-24 12:41:44" Completed="2019-03-24 12:41:44" Duration="0.127901000" Runtime="0.125998000" Status="Successful" LastNote="" Node="10" NodeType="ZWaveDimmableLight" NodeDescription="Bar Lights"/> <0x7721e520>
02	03/24/19 12:41:44.230	Device_Basic::AddPoll 8 poll list full, deleting old one <0x7721e520>
01	03/24/19 12:41:48.049	LuaInterface::CallFunction_Timer device 212 refreshCache took 10 seconds <0x7461e520>
01	03/24/19 12:41:55.364	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:41:55.464	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
01	03/24/19 12:41:55.564	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
01	03/24/19 12:41:55.664	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 251 <0x7721e520>
50	03/24/19 12:42:00.015	luup_log:275: Reactor(debug): tick("13") pluginDevice=275 <0x7241e520>
50	03/24/19 12:42:00.016	luup_log:275: Reactor(debug): tick() inserting eligible task "275" when 1553445720(03/24/19.12:42:00) now 1553445720(03/24/19.12:42:00) <0x7241e520>
50	03/24/19 12:42:00.017	luup_log:275: Reactor(debug): tick() to-do list is { 1={ info="", args={  }, id="275", owner=275, func=function: 0x1f3c318 } } <0x7241e520>
50	03/24/19 12:42:00.017	luup_log:275: Reactor(debug): tick() calling "masterTick"(275,"275") for 275 (task "Reactor" "masterTick") <0x7241e520>
50	03/24/19 12:42:00.018	luup_log:275: Reactor(debug): masterTick(275) <0x7241e520>
50	03/24/19 12:42:00.019	luup_log:275: Reactor(debug): scheduleTick("275",1553445780(03/24/19.12:43:00),nil) <0x7241e520>
50	03/24/19 12:42:00.019	luup_log:275: Reactor(debug): masterTick() current DST "1", last "1" <0x7241e520>
50	03/24/19 12:42:00.020	luup_log:275: Reactor(debug): tick() successful return from "masterTick"(275) <0x7241e520>
50	03/24/19 12:42:00.020	luup_log:275: Reactor(debug): tick() next eligible task scheduled for "03/24/19 12:43:00" <0x7241e520>
50	03/24/19 12:42:00.021	luup_log:275: Reactor(debug): tick() scheduling next tick("13") for 60 (1553445780(03/24/19.12:43:00)) <0x7241e520>
01	03/24/19 12:42:07.180	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:42:07.259	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:42:07.338	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
01	03/24/19 12:42:07.405	luup_log:186: YamahaRxHTTP debug: Error: updateVariable was supplied with a nil value <0x7541e520>
04	03/24/19 12:42:14.221	<Job ID="507" Name="pollnode #11 1 cmds" Device="9" Created="2019-03-24 12:42:14" Started="2019-03-24 12:42:14" Completed="2019-03-24 12:42:14" Duration="0.119886000" Runtime="0.118120000" Status="Successful" LastNote="" Node="11" NodeType="ZWaveDimmableLight" NodeDescription="Living Lights"/> <0x7721e520>
02	03/24/19 12:42:14.222	Device_Basic::AddPoll 9 poll list full, deleting old one <0x7721e520>
01	03/24/19 12:42:17.131	LuaInterface::CallFunction_Timer device 273 refreshCache took 10 seconds <0x7261e520>
08	03/24/19 12:42:18.821	JobHandler_LuaUPnP::HandleActionRequest device: 262 service: urn:dcineco-com:serviceId:MSwitch1 action: SetStatus6 <0x70ddd520>
08	03/24/19 12:42:18.821	JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=262 <0x70ddd520>
08	03/24/19 12:42:18.822	JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:dcineco-com:serviceId:MSwitch1 <0x70ddd520>
08	03/24/19 12:42:18.822	JobHandler_LuaUPnP::HandleActionRequest argument action=SetStatus6 <0x70ddd520>
08	03/24/19 12:42:18.822	JobHandler_LuaUPnP::HandleActionRequest argument newStatus6=-1 <0x70ddd520>
5.8887, oseq = 1553440787.355},{name = 'cZone_2_tuner_off', state = true, seq = 1553440787.3554, oseq = 1553440765.8914},{name = 'tZone_2_Tuner_on', state = false, seq = 1553440765.8755, oseq = 1553440787.3409},{name = 'tZone_2_Tuner_off', state = true, seq = 1553440787.3407, oseq = 1553440765.8753},{name = 'cComputer_on', state = true, seq = 1553390123.4866, oseq = 1553387080.3198},{name = 'cComputer_off', state = false, seq = 1553387080.3205, oseq = 1553390123.4901},{name = 'tComputer_on', state = true, seq = 1553390123.4714, oseq = 1553387080.2949},{name = 'tComputer_off', state = false, seq = 1553387080.2947, oseq = 1553390123.4712},{name = 'cPing_Computer_off', state = false, seq = 1553394264.1398, oseq = 1553428754.149},{name = 'cPing_Computer_on', state = true, seq = 1553428754.1496, oseq = 1553394264.1416},{name = 'Zone_2_power', state = 'Standby', seq = 1553440114.2092, oseq = 1553439873.2076},{name = 'Zone_Main_power', state = 'Standby', seq = 1553380085.2628, oseq = 1553380023.1641},{name = 'pZone_2_Input', state = 'AV3', seq = 1553440087.062, oseq = 1553440078.3847},{name = 'pMain_Zone_input', state = 'AV3', seq = 1553440086.7357, oseq = 1553440078.058},{name = 'tSony_TV_off', state = false, seq = 1553441056.1649, oseq = 1553441124.9349},{name = 'tSony_TV_on', state = true, seq = 1553441124.9352, oseq = 1553441056.1654},{name = 'cAV1', state = false, seq = 1553385121.9746, oseq = 1553385243.9343},{name = 'pZone_2_HT_Flag', state = '0', seq = 1553084752.8749, oseq = 1553084738.0988},{name = 'cAV2', state = false, seq = 1553440078.0755, oseq = 1553440086.7533},{name = 'cAV3', state = false, seq = 1553440086.7549, oseq = 1553440114.2556},{name = 'cAV4', state = false, seq = 1553385066.5136, oseq = 1553385120.8305},{name = 'cZone_2_HT_off', state = true, seq = 1553440114.2578, oseq = 1553439873.2311},{name = 'cAV3_Main_Zone', state = true, seq = 1553440086.7584, oseq = 1553440078.0806},{name = 'pComputer_Ping', state = '1', seq = 1553428754.1324, oseq = 1553394264.1219},{name = 'cZone2_Server', state = false, seq = 1546784359.2692, oseq = 1546784389.3072},{name = 'cZone2_Tuner', state = false, seq = 1546792465.2764, oseq = 1546792470.5562},{name = 'tMain', state = false, seq = 1553445383.3784, oseq = 1553445621.1275},{name = 'tZone2', state = false, seq = 1553439911.049, oseq = 1553442408.1709},{name = 'tZone3', state = false, seq = 1553276693.79, oseq = 1553302173.3798},{name = 'cSony_PS3_on', state = false, seq = 1549743597.2601, oseq = 1549744066.7299},{name = 'tSony_PS3_on', state = false, seq = 1549743597.2376, oseq = 1549744066.6932},{name = 'tSony_PS3_off', state = true, seq = 1549744066.6925, oseq = 1549743597.2374},{name = 'cSony_TV_Ping_on', state = true, seq = 1553441168.1766, oseq = 1553441088.1646},{name = 'cSony_TV_Ping_off', state = false, seq = 1553441088.1652, oseq = 1553441168.1804},{name = 'cSamsung_TV_PIng_on', state = true, seq = 1553444192.2174, oseq = 1553443992.1763},{name = 'cSamsung_TV_PIng_off', state = false, seq = 1553443992.1769, oseq = 1553444192.2205},{name = 'pZone2_TV_Ping', state = '1', seq = 1553441168.152, oseq = 1553441088.135},{name = 'pMain_Zone_TV_Ping', state = '1', seq = 1553444192.1745, oseq = 1553443992.1216},{name = 'pWOL_Diskstation', state = '0', seq = 1553401865.1743, oseq = 1553387147.0724},{name = 'cWOL_Diskstation_Status_off', state = true, seq = 1553401865.2024, oseq = 1553387147.1059},{name = 'tTV_on', state = false, seq = 1553440785.6219, oseq = 1553440787.435},{name = 'cZone2_TV_on_PS3', state = false, seq = 1553275488.0291, oseq = 1553275488.3143},{name = 'cZone2_TV_on_Windows', state = false, seq = 1553275513.1585, oseq = 1553275513.4594},{name = 'cZone2_TV_on_Nvidia', state = false, seq = 1553440785.6493, oseq = 1553440787.4628},{name = 'cZone2_TV_Tuner', state = false, seq = 1553442354.6517, oseq = 1553442408.2306},{name = 'tTuner_on', state = false, seq = 1553445393.8365, oseq = 1553445615.2869},{name = 'cMainTV_on_PS3', state = false, seq = 1553275898.181, oseq = 1553276801.7183},{name = 'cZone2_TV_off', state = false, seq = 1550240323.9485, oseq = 15
50240324.1335},{name = 'cMain_TV_off', state = false, seq = 1552239629.5689, oseq = 1552239635.9918},{name = 'TV_off', state = false, seq = 1552239629.5382, oseq = 1552239635.9347},{name = 'cMain_TV_Tuner', state = false, seq = 1553445393.9011, oseq = 1553445615.3212},{name = 'cZone2_TV_on_Chromecast', state = false, seq = 1553170316.0324, oseq = 1553170317.0925},{name = 'cMain_TV_on_PS3', state = false, seq = 1553276717.1829, oseq = 1553276795.3214},{name = 'cMain_TV_on_Windows', state = false, seq = 1553208506.6083, oseq = 1553208506.8792},{name = 'cMain_TV_on_Nvidia', state = false, seq = 1553439468.3466, oseq = 1553439722.7885},{name = 'cMain_TV_on_Chromecast', state = false, seq = 1529149426.3507, oseq = 1529149426.7496},{name = 'tAV1', state = false, seq = 1553439837.4803, oseq = 1553439838.221},{name = 'tAV2', state = false, seq = 1553440077.4342, oseq = 1553440078.5047},{name = 'tAV3', state = false, seq = 1553440785.4913, oseq = 1553440787.5199},{name = 'tAv4', state = false, seq = 1553439844.2064, oseq = 1553439844.91},{name = 'cZone2_TVHT_PS3', state = false, seq = 1547850570.0389, oseq = 1547850628.4654},{name = 'cZone2_TVHT_Windows', state = false, seq = 1547917335.1136, oseq = 1547917335.4904},{name = 'cZone2_TVHT_Nvidia', state = false, seq = 1549729803.0838, oseq = 1549729804.3337},{name = 'cZone2_TVHT_Chromecast', state = false, seq = 1529149145.1002, oseq = 1529149145.5052},{name = 'cZone2_Receiver_on', state = false, seq = 1553084733.8582, oseq = 1553084734.241},{name = 'cMain_Receiver_on', state = false, seq = 1549655971.1694, oseq = 1549655972.1049},{name = 'tReceiver_on', state = false, seq = 1553084733.8158, oseq = 1553084734.2038},{name = 'cZone2_Receiver_off', state = false, seq = 1553386858.0263, oseq = 1553386858.3279},{name = 'cMain_Receiver_off', state = false, seq = 1551554135.9543, oseq = 1551554138.6642},{name = 'tReceiver_off', state = false, seq = 1553386857.9814, oseq = 1553386858.2883},{name = 'cMain_TVHT_PS3', state = false, seq = 1529194985.2414, oseq = 1529194985.9039},{name = 'cMain_TVHT_Windows', state = false, seq = 1529195602.3083, oseq = 1529195602.9737},{name = 'cMain_TVHT_Nvidia', state = false, seq = 1532141743.1662, oseq = 1532141743.486},{name = 'cMain_TVHT_Chromecast', state = false, seq = 1529195619.7214, oseq = 1529195620.0893},{name = 'cZone2_TV_Nvidia', state = false, seq = 0, oseq = 0},{name = 'cWOL_Diskstation_Status_on', state = false, seq = 1553387147.1041, oseq = 1553401865.2017},{name = 'sSet_Zone2', state = false, seq = 1553410800.1006, oseq = 1553410800.1609},{name = 'cSet_Zone_2', state = false, seq = 1553410800.1538, oseq = 1553429141.4431},{name = 'cMain_AV3_Music', state = false, seq = 1543951640.2232, oseq = 1543951640.5402},{name = 'tGlobal_TV_off', state = true, seq = 1553440787.4343, oseq = 1553440785.6214},{name = 'cZone2_AV3_Music', state = false, seq = 1551552847.3336, oseq = 1551552847.9927},{name = 'tGlobal_Shutdown', state = false, seq = 1553315607.2838, oseq = 1553315607.5401},{name = 'cZone3_Nvidia', state = false, seq = 1551548748.7316, oseq = 1551548749.3297},{name = 'cZone3_Chromecast', state = false, seq = 0, oseq = 0},{name = 'cZone3_Receiver_off', state = false, seq = 1551554119.2085, oseq = 1551554119.494},{name = 'cZone3_Receiver_on', state = false, seq = 1551548748.7356, oseq = 1551548749.3312},{name = 'cGlobal_Receiver_off', state = false, seq = 1553315607.4734, oseq = 1553315609.4508},{name = 'tGlobal_Receiver_off', state = false, seq = 1553315607.4237, oseq = 1553315609.3992},{name = 'cPS3_Ping_on', state = false, seq = 1553169805.2422, oseq = 1553176547.1753},{name = 'tPS3_Ping_on', state = true, seq = 1549668388.7905, oseq = 0},{name = 'tPS3_Ping_off', state = false, seq = 0, oseq = 0},{name = 'pPS3_Ping', state = '0', seq = 1553176547.1256, oseq = 1553169805.1523},{name = 'cGlobal_Shutdown', state = false, seq = 1553315607.334, oseq = 1553315607.5903},{name = 'sReset_Device_Holds', state = false, seq = 1553411400.0823, oseq = 1553411400.1375},{name = 'cReset_Device_Holds', state = false, seq = 1553411400.135, oseq = 1553429141.4
475},{name = 'cMonitor_Hold_off', state = false, seq = 1553387080.392, oseq = 1553390123.5252},{name = 'tMonitor_Hold_off', state = true, seq = 1552053004.8878, oseq = 1552052998.6333},{name = 'cMonitor_off', state = false, seq = 1553387147.2911, oseq = 1553390123.8957},{name = 'tMonitor_off', state = false, seq = 1553387147.242, oseq = 1553390123.8454},{name = 'cPS3_Ping_off', state = true, seq = 1553176547.1768, oseq = 1553169805.2465},} now: {{name = 'cMatrixB1', state = true, seq = 1553440114.2145, oseq = 1553439873.2104},{name = 'cMatrixB2', state = false, seq = 1553439873.2111, oseq = 1553440114.2372},{name = 'tMatrixB1', state = false, seq = 1553380767.3161, oseq = 1553380788.5426},{name = 'tMatrixB2', state = true, seq = 1553380788.5429, oseq = 1553380767.3164},{name = 'cSony_HDMI_1_off', state = false, seq = 1553440766.051, oseq = 1553440787.6149},{name = 'cSony_HDMI_1_on', state = true, seq = 1553440787.6155, oseq = 1553440766.0537},{name = 'tSony_HDMI_1_off', state = false, seq = 1553440766.0476, oseq = 1553440787.6114},{name = 'tSony_HDMI_1_on', state = true, seq = 1553440787.6116, oseq = 1553440766.0478},{name = 'cSony_TV_off', state = false, seq = 1553441056.1753, oseq = 1553441124.9566},{name = 'pHDMI_Matrix_B1_B2', state = '1', seq = 1553380788.6327, oseq = 1553380767.4061},{name = 'cSony_TV_on_Tuner_delayed', state = false, seq = 1550661799.4074, oseq = 1550661843.7572},{name = 'cSony_TV_on', state = true, seq = 1553441124.9572, oseq = 1553441056.1988},{name = 'cSony_Tuner_HT_on', state = false, seq = 1546959402.8948, oseq = 1546966675.7579},{name = 'cSony_HT_off', state = true, seq = 1553440114.2401, oseq = 1553439873.2151},{name = 'tSony_HT_off', state = true, seq = 1553084752.7813, oseq = 1553084738.018},{name = 'tSony_HT_on', state = false, seq = 1553084738.0182, oseq = 1553084752.7816},{name = 'tSamsung_HT_on', state = false, seq = 1552841797.7367, oseq = 1552961397.6164},{name = 'tSamsung_HT_off', state = true, seq = 1552961397.6162, oseq = 1552841797.7365},{name = 'cSamsung_TV_on_Tuner_Delayed', state = false, seq = 1546954176.7697, oseq = 1546954196.1308},{name = 'cSamsung_HDMI_2_off', state = false, seq = 1546961790.4182, oseq = 1546966639.0616},{name = 'cSamsung_HDMI_2_on', state = true, seq = 1546966639.0621, oseq = 1546961790.421},{name = 'tSamsung_HDMI_2_off', state = false, seq = 1546961790.4112, oseq = 1546966639.0535},{name = 'tSamsung_HDMI_2_on', state = true, seq = 1546966639.0537, oseq = 1546961790.4115},{name = 'cSamsung_TV_off', state = true, seq = 1552239629.6414, oseq = 1552230500.6075},{name = 'cSamsung_HT_off', state = true, seq = 1553380085.2831, oseq = 1553380023.1809},{name = 'cSamsung_HT_on', state = false, seq = 1553380023.1824, oseq = 1553380085.3054},{name = 'cSamsung__TV_Tuner_HT_on', state = false, seq = 1546961790.0482, oseq = 1546966640.2229},{name = 'pSony_Windows', state = '0', seq = 1547846218.2943, oseq = 1547846199.3101},{name = 'pSony_Nvidia', state = '0', seq = 1528424516.3177, oseq = 946684997.59476},{name = 'pSony_Chromecast', state = '0', seq = 1513669538.2022, oseq = 1513669530.1802},{name = 'cSamsung_TV_on', state = false, seq = 1552230500.61, oseq = 1552239629.6459},{name = 'cSony_HT_on_TV_on', state = false, seq = 1553393134.2978, oseq = 1553393165.2779},{name = 'cSony_HT_on_TV_off', state = false, seq = 1553084746.2794, oseq = 1553084752.7932},{name = 'cNest_Home', state = false, seq = 1553129016.4267, oseq = 1553179262.051},{name = 'cNest_Away', state = true, seq = 1553179262.0513, oseq = 1553129016.4294},{name = 'tHome_mode', state = false, seq = 1553129016.4029, oseq = 1553179262.0402},{name = 'tAway_mode', state = true, seq = 1553179262.0399, oseq = 1553129016.4023},{name = 'tSamsung_Tuner', state = false, seq = 1546961790.0394, oseq = 1546966640.2148},{name = 'All_TV_HT_off', state = false, seq = 1553441056.2066, oseq = 1553441124.9959},{name = 'tSamsung_TV_on', state = false, seq = 1552230500.6002, oseq = 1552239629.6342},{name = 'tSamsung_TV_off', state = true, seq = 1552239629.634, oseq = 1552230500.6},{name = 'cSamsung_HDMI', state = true, seq 
= 1546966640.2274, oseq = 1546961790.0552},{name = 'cSamsung_Antenna', state = false, seq = 1546961790.056, oseq = 1546966640.2305},{name = 'tZone2_HT_off_trigger', state = false, seq = 946685117.39239, oseq = 1528494009.1336},{name = 'cZon2_2_tuner_on', state = false, seq = 1553440765.8887, oseq = 1553440787.355},{name = 'cZone_2_tuner_off', state = true, seq = 1553440787.3554, oseq = 1553440765.8914},{name = 'tZone_2_Tuner_on', state = false, seq = 1553440765.8755, oseq = 1553440787.3409},{name = 'tZone_2_Tuner_off', state = true, seq = 1553440787.3407, oseq = 1553440765.8753},{name = 'cComputer_on', state = true, seq = 1553390123.4866, oseq = 1553387080.3198},{name = 'cComputer_off', state = false, seq = 1553387080.3205, oseq = 1553390123.4901},{name = 'tComputer_on', state = true, seq = 1553390123.4714, oseq = 1553387080.2949},{name = 'tComputer_off', state = false, seq = 1553387080.2947, oseq = 1553390123.4712},{name = 'cPing_Computer_off', state = false, seq = 1553394264.1398, oseq = 1553428754.149},{name = 'cPing_Computer_on', state = true, seq = 1553428754.1496, oseq = 1553394264.1416},{name = 'Zone_2_power', state = 'Standby', seq = 1553440114.2092, oseq = 1553439873.2076},{name = 'Zone_Main_power', state = 'Standby', seq = 1553380085.2628, oseq = 1553380023.1641},{name = 'pZone_2_Input', state = 'AV3', seq = 1553440087.062, oseq = 1553440078.3847},{name = 'pMain_Zone_input', state = 'AV3', seq = 1553440086.7357, oseq = 1553440078.058},{name = 'tSony_TV_off', state = false, seq = 1553441056.1649, oseq = 1553441124.9349},{name = 'tSony_TV_on', state = true, seq = 1553441124.9352, oseq = 1553441056.1654},{name = 'cAV1', state = false, seq = 1553385121.9746, oseq = 1553385243.9343},{name = 'pZone_2_HT_Flag', state = '0', seq = 1553084752.8749, oseq = 1553084738.0988},{name = 'cAV2', state = false, seq = 1553440078.0755, oseq = 1553440086.7533},{name = 'cAV3', state = false, seq = 1553440086.7549, oseq = 1553440114.2556},{name = 'cAV4', state = false, seq = 1553385066.5136, oseq = 1553385120.8305},{name = 'cZone_2_HT_off', state = true, seq = 1553440114.2578, oseq = 1553439873.2311},{name = 'cAV3_Main_Zone', state = true, seq = 1553440086.7584, oseq = 1553440078.0806},{name = 'pComputer_Ping', state = '1', seq = 1553428754.1324, oseq = 1553394264.1219},{name = 'cZone2_Server', state = false, seq = 1546784359.2692, oseq = 1546784389.3072},{name = 'cZone2_Tuner', state = false, seq = 1546792465.2764, oseq = 1546792470.5562},{name = 'tMain', state = true, seq = 1553445738.8281, oseq = 1553445621.1275},{name = 'tZone2', state = false, seq = 1553439911.049, oseq = 1553442408.1709},{name = 'tZone3', state = false, seq = 1553276693.79, oseq = 1553302173.3798},{name = 'cSony_PS3_on', state = false, seq = 1549743597.2601, oseq = 1549744066.7299},{name = 'tSony_PS3_on', state = false, seq = 1549743597.2376, oseq = 1549744066.6932},{name = 'tSony_PS3_off', state = true, seq = 1549744066.6925, oseq = 1549743597.2374},{name = 'cSony_TV_Ping_on', state = true, seq = 1553441168.1766, oseq = 1553441088.1646},{name = 'cSony_TV_Ping_off', state = false, seq = 1553441088.1652, oseq = 1553441168.1804},{name = 'cSamsung_TV_PIng_on', state = true, seq = 1553444192.2174, oseq = 1553443992.1763},{name = 'cSamsung_TV_PIng_off', state = false, seq = 1553443992.1769, oseq = 1553444192.2205},{name = 'pZone2_TV_Ping', state = '1', seq = 1553441168.152, oseq = 1553441088.135},{name = 'pMain_Zone_TV_Ping', state = '1', seq = 1553444192.1745, oseq = 1553443992.1216},{name = 'pWOL_Diskstation', state = '0', seq = 1553401865.1743, oseq = 1553387147.0724},{name = 'cWOL_Diskstation_Status_off', state = true, seq = 1553401865.2024, oseq = 1553387147.1059},{name = 'tTV_on', state = false, seq = 1553440785.6219, oseq = 1553440787.435},{name = 'cZone2_TV_on_PS3', state = false, seq = 1553275488.0291, oseq = 1553275488.3143},{name = 'cZone2_TV_on_Windows', state = false, seq = 1553275513.1585, oseq = 1553275513.4594},{name = 'cZone2_TV_on_Nvidia', state = false, seq = 1553440785.6493, oseq = 1553440787.4628},{name = 'cZone2_TV_Tuner', state = false, seq =
 1553442354.6517, oseq = 1553442408.2306},{name = 'tTuner_on', state = false, seq = 1553445393.8365, oseq = 1553445615.2869},{name = 'cMainTV_on_PS3', state = false, seq = 1553275898.181, oseq = 1553276801.7183},{name = 'cZone2_TV_off', state = false, seq = 1550240323.9485, oseq = 1550240324.1335},{name = 'cMain_TV_off', state = false, seq = 1552239629.5689, oseq = 1552239635.9918},{name = 'TV_off', state = false, seq = 1552239629.5382, oseq = 1552239635.9347},{name = 'cMain_TV_Tuner', state = false, seq = 1553445393.9011, oseq = 1553445615.3212},{name = 'cZone2_TV_on_Chromecast', state = false, seq = 1553170316.0324, oseq = 1553170317.0925},{name = 'cMain_TV_on_PS3', state = false, seq = 1553276717.1829, oseq = 1553276795.3214},{name = 'cMain_TV_on_Windows', state = false, seq = 1553208506.6083, oseq = 1553208506.8792},{name = 'cMain_TV_on_Nvidia', state = false, seq = 1553439468.3466, oseq = 1553439722.7885},{name = 'cMain_TV_on_Chromecast', state = false, seq = 1529149426.3507, oseq = 1529149426.7496},{name = 'tAV1', state = false, seq = 1553439837.4803, oseq = 1553439838.221},{name = 'tAV2', state = false, seq = 1553440077.4342, oseq = 1553440078.5047},{name = 'tAV3', state = false, seq = 1553440785.4913, oseq = 1553440787.5199},{name = 'tAv4', state = false, seq = 1553439844.2064, oseq = 1553439844.91},{name = 'cZone2_TVHT_PS3', state = false, seq = 1547850570.0389, oseq = 1547850628.4654},{name = 'cZone2_TVHT_Windows', state = false, seq = 1547917335.1136, oseq = 1547917335.4904},{name = 'cZone2_TVHT_Nvidia', state = false, seq = 1549729803.0838, oseq = 1549729804.3337},{name = 'cZone2_TVHT_Chromecast', state = false, seq = 1529149145.1002, oseq = 1529149145.5052},{name = 'cZone2_Receiver_on', state = false, seq = 1553084733.8582, oseq = 1553084734.241},{name = 'cMain_Receiver_on', state = false, seq = 1549655971.1694, oseq = 1549655972.1049},{name = 'tReceiver_on', state = false, seq = 1553084733.8158, oseq = 1553084734.2038},{name = 'cZone2_Receiver_off', state = false, seq = 1553386858.0263, oseq = 1553386858.3279},{name = 'cMain_Receiver_off', state = false, seq = 1551554135.9543, oseq = 1551554138.6642},{name = 'tReceiver_off', state = false, seq = 1553386857.9814, oseq = 1553386858.2883},{name = 'cMain_TVHT_PS3', state = false, seq = 1529194985.2414, oseq = 1529194985.9039},{name = 'cMain_TVHT_Windows', state = false, seq = 1529195602.3083, oseq = 1529195602.9737},{name = 'cMain_TVHT_Nvidia', state = false, seq = 1532141743.1662, oseq = 1532141743.486},{name = 'cMain_TVHT_Chromecast', state = false, seq = 1529195619.7214, oseq = 1529195620.0893},{name = 'cZone2_TV_Nvidia', state = false, seq = 0, oseq = 0},{name = 'cWOL_Diskstation_Status_on', state = false, seq = 1553387147.1041, oseq = 1553401865.2017},{name = 'sSet_Zone2', state = false, seq = 1553410800.1006, oseq = 1553410800.1609},{name = 'cSet_Zone_2', state = false, seq = 1553410800.1538, oseq = 1553429141.4431},{name = 'cMain_AV3_Music', state = false, seq = 1543951640.2232, oseq = 1543951640.5402},{name = 'tGlobal_TV_off', state = true, seq = 1553440787.4343, oseq = 1553440785.6214},{name = 'cZone2_AV3_Music', state = false, seq = 1551552847.3336, oseq = 1551552847.9927},{name = 'tGlobal_Shutdown', state = false, seq = 1553315607.2838, oseq = 1553315607.5401},{name = 'cZone3_Nvidia', state = false, seq = 1551548748.7316, oseq = 1551548749.3297},{name = 'cZone3_Chromecast', state = false, seq = 0, oseq = 0},{name = 'cZone3_Receiver_off', state = false, seq = 1551554119.2085, oseq = 1551554119.494},{name = 'cZone3_Receiver_on', state = false, seq = 1551548748.7356, oseq = 1551548749.3312},{name = 'cGlobal_Receiver_off', state = false, seq = 1553315607.4734, oseq = 1553315609.4508},{name = 'tGlobal_Receiver_off', state = false, seq = 1553315607.4237, oseq = 1553315609.3992},{name = 'cPS3_Ping_on', state = false, seq = 1553169805.2422, oseq = 1553176547.1753},{name = 'tPS3_Ping_on', state = true, seq = 1549668388.7905, oseq = 0},{name = 'tPS3_Ping_off', state = false, seq = 0, oseq = 0},{name = 'pPS3_Ping', state = '0', seq = 1553176547.1256, oseq = 15
53169805.1523},{name = 'cGlobal_Shutdown', state = false, seq = 1553315607.334, oseq = 1553315607.5903},{name = 'sReset_Device_Holds', state = false, seq = 1553411400.0823, oseq = 1553411400.1375},{name = 'cReset_Device_Holds', state = false, seq = 1553411400.135, oseq = 1553429141.4475},{name = 'cMonitor_Hold_off', state = false, seq = 1553387080.392, oseq = 1553390123.5252},{name = 'tMonitor_Hold_off', state = true, seq = 1552053004.8878, oseq = 1552052998.6333},{name = 'cMonitor_off', state = false, seq = 1553387147.2911, oseq = 1553390123.8957},{name = 'tMonitor_off', state = false, seq = 1553387147.242, oseq = 1553390123.8454},{name = 'cPS3_Ping_off', state = true, seq = 1553176547.1768, oseq = 1553169805.2465},} #hooks: 0 upnp: 0 skip: 0 v:0x11ce758/NONE duplicate:0 <0x77eae320>
50	03/24/19 12:42:18.945	luup_log:275: Reactor(debug): watch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1") <0x77eae320>
50	03/24/19 12:42:18.945	luup_log:275: Reactor(debug): watch() dispatching to 287 ("Yamaha Input Routine") <0x77eae320>
50	03/24/19 12:42:18.946	luup_log:275: Reactor(debug): sensorWatch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1",287,275) <0x77eae320>
50	03/24/19 12:42:18.948	luup_log:275: Reactor(debug): updateSensor(287) "Yamaha Input Routine" <0x77eae320>
50	03/24/19 12:42:18.949	luup_log:275: Reactor(debug): updateSensor() disabled; no action <0x77eae320>
50	03/24/19 12:42:18.949	luup_log:275: Reactor(debug): watch() dispatching to 290 ("TV Tuner Routine") <0x77eae320>
50	03/24/19 12:42:18.950	luup_log:275: Reactor(debug): sensorWatch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1",290,275) <0x77eae320>
50	03/24/19 12:42:18.951	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x77eae320>
50	03/24/19 12:42:18.952	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:18.952	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:18.954	luup_log:275: Reactor(debug): updateSensor() base time is 1553445738(03/24/19.12:42:18) ({ hour=12, min=42, wday=1, day=24, month=3, year=2019, sec=18, yday=83, isdst=true }) <0x77eae320>
50	03/24/19 12:42:18.954	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x77eae320>
50	03/24/19 12:42:18.955	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:18.955	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:18.956	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:18.956	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:18.956	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:18.957	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine", laststate={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 } } <0x77eae320>
50	03/24/19 12:42:18.958	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.958	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.958	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x77eae320>
50	03/24/19 12:42:18.959	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x77eae320>
50	03/24/19 12:42:18.965	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445621(03/24/19.12:40:21), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445621(03/24/19.12:40:21), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445621(03/24/19.12:40:21), laststate=false }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:18.966	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.966	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.967	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.971	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445621(03/24/19.12:40:21), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445621(03/24/19.12:40:21), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445621(03/24/19.12:40:21), laststate=false }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:18.972	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.972	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.973	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.975	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445621(03/24/19.12:40:21), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445621(03/24/19.12:40:21), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445621(03/24/19.12:40:21), laststate=false }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x77eae320>
50	03/24/19 12:42:18.975	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.976	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.976	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:18.977	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:18.977	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33fwzm", new value="1" <0x77eae320>
50	03/24/19 12:42:18.977	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:18.978	luup_log:275: Reactor(debug): processCondition() handling "condb33fwzm" state changed from false to true <0x77eae320>
50	03/24/19 12:42:18.979	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:18.981	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } <0x77eae320>
50	03/24/19 12:42:18.982	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.982	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.983	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:18.983	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "0""=""1"? <0x77eae320>
50	03/24/19 12:42:18.983	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state false timer nil <0x77eae320>
50	03/24/19 12:42:18.984	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final false, group now false <0x77eae320>
50	03/24/19 12:42:18.984	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state false timer false <0x77eae320>
50	03/24/19 12:42:18.985	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:18.985	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state false timer false <0x77eae320>
50	03/24/19 12:42:18.986	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33c7p3" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:18.991	luup_log:275: Reactor(debug): evaluateGroup() process #3 in "root": { type="group", name="Main TV Ping On", id="grpb3baia5", conditions={ 1={ type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 }, 2={ type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553445615(03/24/19.12:40:15) }, id="grpb3baia5", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:18.991	luup_log:275: Reactor(debug): processCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.992	luup_log:275: Reactor(debug): evaluateCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.992	luup_log:275: Reactor(debug): evaluateGroup("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.994	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb3baia5": { type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 } <0x77eae320>
50	03/24/19 12:42:18.995	luup_log:275: Reactor(debug): processCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.995	luup_log:275: Reactor(debug): evaluateCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:18.995	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:18.996	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:upnp-org:serviceId:SwitchPower1"/"Status" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:18.996	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bb0iv", new value="1" <0x77eae320>
50	03/24/19 12:42:18.997	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bb0iv" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:18.997	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bb0iv" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:18.999	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb3baia5": { type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } <0x77eae320>
50	03/24/19 12:42:19.000	luup_log:275: Reactor(debug): processCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.000	luup_log:275: Reactor(debug): evaluateCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.001	luup_log:275: Reactor(debug): getValue("",nil,290) <0x77eae320>
50	03/24/19 12:42:19.001	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:toggledbits-com:serviceId:ReactorGroup"/"GroupStatus_grpb33c7p3" "0""istrue"""? <0x77eae320>
50	03/24/19 12:42:19.002	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bbob2" result is state false timer nil <0x77eae320>
50	03/24/19 12:42:19.002	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bbob2" "service" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.002	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb3baia5" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.003	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb3baia5" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.003	luup_log:275: Reactor(debug): processCondition() eval group nil cond "root" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.004	luup_log:275: Reactor(debug): updateSensor() trip ""was false now false, retrig false <0x77eae320>
50	03/24/19 12:42:19.106	luup_log:275: Reactor(debug): updateSensor() trouble false <0x77eae320>
45615, "stateedge": { "1": 1553444192, "0": 1553445615 }, "id": "grpb3baia5", "evalstamp": 1553445615, "lastvalue": false, "evalstate": false, "priorvalue": true, "valuestamp": 1553445615, "laststate": false } } #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x77eae320>
50	03/24/19 12:42:19.113	luup_log:275: Reactor(debug): watch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1") <0x77eae320>
50	03/24/19 12:42:19.113	luup_log:275: Reactor(debug): watch() dispatching to 287 ("Yamaha Input Routine") <0x77eae320>
50	03/24/19 12:42:19.114	luup_log:275: Reactor(debug): sensorWatch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1",287,275) <0x77eae320>
50	03/24/19 12:42:19.115	luup_log:275: Reactor(debug): updateSensor(287) "Yamaha Input Routine" <0x77eae320>
50	03/24/19 12:42:19.115	luup_log:275: Reactor(debug): updateSensor() disabled; no action <0x77eae320>
50	03/24/19 12:42:19.116	luup_log:275: Reactor(debug): watch() dispatching to 290 ("TV Tuner Routine") <0x77eae320>
50	03/24/19 12:42:19.116	luup_log:275: Reactor(debug): sensorWatch(262,"urn:dcineco-com:serviceId:MSwitch1","Status6","0","1",290,275) <0x77eae320>
50	03/24/19 12:42:19.117	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x77eae320>
50	03/24/19 12:42:19.118	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:19.118	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:19.119	luup_log:275: Reactor(debug): updateSensor() base time is 1553445739(03/24/19.12:42:19) ({ hour=12, min=42, wday=1, day=24, month=3, year=2019, sec=19, yday=83, isdst=true }) <0x77eae320>
50	03/24/19 12:42:19.120	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x77eae320>
50	03/24/19 12:42:19.120	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:19.120	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:19.121	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:19.121	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:19.122	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:19.122	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine", laststate={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 } } <0x77eae320>
50	03/24/19 12:42:19.123	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.123	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.123	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x77eae320>
50	03/24/19 12:42:19.124	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x77eae320>
50	03/24/19 12:42:19.130	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true, changed=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:19.131	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.132	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.132	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.137	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true, changed=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:19.138	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.138	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.138	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.141	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true, changed=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x77eae320>
50	03/24/19 12:42:19.142	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.142	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.142	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:19.143	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:19.143	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33fwzm", new value="1" <0x77eae320>
50	03/24/19 12:42:19.143	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:19.144	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:19.146	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } <0x77eae320>
50	03/24/19 12:42:19.146	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.147	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.147	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:19.148	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "0""=""1"? <0x77eae320>
50	03/24/19 12:42:19.148	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state false timer nil <0x77eae320>
50	03/24/19 12:42:19.148	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.149	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.149	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.150	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.150	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33c7p3" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.155	luup_log:275: Reactor(debug): evaluateGroup() process #3 in "root": { type="group", name="Main TV Ping On", id="grpb3baia5", conditions={ 1={ type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 }, 2={ type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553445615(03/24/19.12:40:15) }, id="grpb3baia5", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:19.156	luup_log:275: Reactor(debug): processCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.156	luup_log:275: Reactor(debug): evaluateCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.157	luup_log:275: Reactor(debug): evaluateGroup("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.159	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb3baia5": { type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 } <0x77eae320>
50	03/24/19 12:42:19.159	luup_log:275: Reactor(debug): processCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.160	luup_log:275: Reactor(debug): evaluateCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.160	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:19.161	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:upnp-org:serviceId:SwitchPower1"/"Status" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:19.161	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bb0iv", new value="1" <0x77eae320>
50	03/24/19 12:42:19.161	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bb0iv" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:19.162	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bb0iv" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:19.164	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb3baia5": { type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } <0x77eae320>
50	03/24/19 12:42:19.164	luup_log:275: Reactor(debug): processCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.165	luup_log:275: Reactor(debug): evaluateCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:19.165	luup_log:275: Reactor(debug): getValue("",nil,290) <0x77eae320>
50	03/24/19 12:42:19.166	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:toggledbits-com:serviceId:ReactorGroup"/"GroupStatus_grpb33c7p3" "0""istrue"""? <0x77eae320>
50	03/24/19 12:42:19.166	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bbob2" result is state false timer nil <0x77eae320>
50	03/24/19 12:42:19.167	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bbob2" "service" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.167	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb3baia5" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.168	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb3baia5" "group" final false, group now false <0x77eae320>
50	03/24/19 12:42:19.168	luup_log:275: Reactor(debug): processCondition() eval group nil cond "root" result is state false timer false <0x77eae320>
50	03/24/19 12:42:19.169	luup_log:275: Reactor(debug): updateSensor() trip ""was false now false, retrig false <0x77eae320>
50	03/24/19 12:42:19.169	luup_log:275: Reactor(debug): updateSensor() trouble false <0x77eae320>
testamp": 1553445615, "stateedge": { "1": 1553444192, "0": 1553445615 }, "id": "grpb3baia5", "evalstamp": 1553445615, "lastvalue": false, "evalstate": false, "priorvalue": true, "valuestamp": 1553445615, "laststate": false } } #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x77eae320>
04	03/24/19 12:42:19.177	<Job ID="508" Name="" Device="262" Created="2019-03-24 12:42:18" Started="2019-03-24 12:42:18" Completed="2019-03-24 12:42:19" Duration="0.353629000" Runtime="0.351988000" Status="Successful" LastNote=""/> <0x77eae320>
50	03/24/19 12:42:21.160	luup_log:233: VeraConnectWWN::getStreamEvents> No 'smoke_co_alarms' in json <0x73e1e520>
08	03/24/19 12:42:25.099	JobHandler_LuaUPnP::HandleActionRequest device: 262 service: urn:dcineco-com:serviceId:MSwitch1 action: SetStatus2 <0x70ddd520>
08	03/24/19 12:42:25.099	JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=262 <0x70ddd520>
08	03/24/19 12:42:25.100	JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:dcineco-com:serviceId:MSwitch1 <0x70ddd520>
08	03/24/19 12:42:25.100	JobHandler_LuaUPnP::HandleActionRequest argument action=SetStatus2 <0x70ddd520>
08	03/24/19 12:42:25.100	JobHandler_LuaUPnP::HandleActionRequest argument newStatus2=-1 <0x70ddd520>
5.8887, oseq = 1553440787.355},{name = 'cZone_2_tuner_off', state = true, seq = 1553440787.3554, oseq = 1553440765.8914},{name = 'tZone_2_Tuner_on', state = false, seq = 1553440765.8755, oseq = 1553440787.3409},{name = 'tZone_2_Tuner_off', state = true, seq = 1553440787.3407, oseq = 1553440765.8753},{name = 'cComputer_on', state = true, seq = 1553390123.4866, oseq = 1553387080.3198},{name = 'cComputer_off', state = false, seq = 1553387080.3205, oseq = 1553390123.4901},{name = 'tComputer_on', state = true, seq = 1553390123.4714, oseq = 1553387080.2949},{name = 'tComputer_off', state = false, seq = 1553387080.2947, oseq = 1553390123.4712},{name = 'cPing_Computer_off', state = false, seq = 1553394264.1398, oseq = 1553428754.149},{name = 'cPing_Computer_on', state = true, seq = 1553428754.1496, oseq = 1553394264.1416},{name = 'Zone_2_power', state = 'Standby', seq = 1553440114.2092, oseq = 1553439873.2076},{name = 'Zone_Main_power', state = 'Standby', seq = 1553380085.2628, oseq = 1553380023.1641},{name = 'pZone_2_Input', state = 'AV3', seq = 1553440087.062, oseq = 1553440078.3847},{name = 'pMain_Zone_input', state = 'AV3', seq = 1553440086.7357, oseq = 1553440078.058},{name = 'tSony_TV_off', state = false, seq = 1553441056.1649, oseq = 1553441124.9349},{name = 'tSony_TV_on', state = true, seq = 1553441124.9352, oseq = 1553441056.1654},{name = 'cAV1', state = false, seq = 1553385121.9746, oseq = 1553385243.9343},{name = 'pZone_2_HT_Flag', state = '0', seq = 1553084752.8749, oseq = 1553084738.0988},{name = 'cAV2', state = false, seq = 1553440078.0755, oseq = 1553440086.7533},{name = 'cAV3', state = false, seq = 1553440086.7549, oseq = 1553440114.2556},{name = 'cAV4', state = false, seq = 1553385066.5136, oseq = 1553385120.8305},{name = 'cZone_2_HT_off', state = true, seq = 1553440114.2578, oseq = 1553439873.2311},{name = 'cAV3_Main_Zone', state = true, seq = 1553440086.7584, oseq = 1553440078.0806},{name = 'pComputer_Ping', state = '1', seq = 1553428754.1324, oseq = 1553394264.1219},{name = 'cZone2_Server', state = false, seq = 1546784359.2692, oseq = 1546784389.3072},{name = 'cZone2_Tuner', state = false, seq = 1546792465.2764, oseq = 1546792470.5562},{name = 'tMain', state = true, seq = 1553445738.8281, oseq = 1553445621.1275},{name = 'tZone2', state = false, seq = 1553439911.049, oseq = 1553442408.1709},{name = 'tZone3', state = false, seq = 1553276693.79, oseq = 1553302173.3798},{name = 'cSony_PS3_on', state = false, seq = 1549743597.2601, oseq = 1549744066.7299},{name = 'tSony_PS3_on', state = false, seq = 1549743597.2376, oseq = 1549744066.6932},{name = 'tSony_PS3_off', state = true, seq = 1549744066.6925, oseq = 1549743597.2374},{name = 'cSony_TV_Ping_on', state = true, seq = 1553441168.1766, oseq = 1553441088.1646},{name = 'cSony_TV_Ping_off', state = false, seq = 1553441088.1652, oseq = 1553441168.1804},{name = 'cSamsung_TV_PIng_on', state = true, seq = 1553444192.2174, oseq = 1553443992.1763},{name = 'cSamsung_TV_PIng_off', state = false, seq = 1553443992.1769, oseq = 1553444192.2205},{name = 'pZone2_TV_Ping', state = '1', seq = 1553441168.152, oseq = 1553441088.135},{name = 'pMain_Zone_TV_Ping', state = '1', seq = 1553444192.1745, oseq = 1553443992.1216},{name = 'pWOL_Diskstation', state = '0', seq = 1553401865.1743, oseq = 1553387147.0724},{name = 'cWOL_Diskstation_Status_off', state = true, seq = 1553401865.2024, oseq = 1553387147.1059},{name = 'tTV_on', state = false, seq = 1553440785.6219, oseq = 1553440787.435},{name = 'cZone2_TV_on_PS3', state = false, seq = 1553275488.0291, oseq = 1553275488.3143},{name = 'cZone2_TV_on_Windows', state = false, seq = 1553275513.1585, oseq = 1553275513.4594},{name = 'cZone2_TV_on_Nvidia', state = false, seq = 1553440785.6493, oseq = 1553440787.4628},{name = 'cZone2_TV_Tuner', state = false, seq = 1553442354.6517, oseq = 1553442408.2306},{name = 'tTuner_on', state = false, seq = 1553445393.8365, oseq = 1553445615.2869},{name = 'cMainTV_on_PS3', state = false, seq = 1553275898.181, oseq = 1553276801.7183},{name = 'cZone2_TV_off', state = false, seq = 1550240323.9485, oseq = 155
0240324.1335},{name = 'cMain_TV_off', state = false, seq = 1552239629.5689, oseq = 1552239635.9918},{name = 'TV_off', state = false, seq = 1552239629.5382, oseq = 1552239635.9347},{name = 'cMain_TV_Tuner', state = false, seq = 1553445393.9011, oseq = 1553445615.3212},{name = 'cZone2_TV_on_Chromecast', state = false, seq = 1553170316.0324, oseq = 1553170317.0925},{name = 'cMain_TV_on_PS3', state = false, seq = 1553276717.1829, oseq = 1553276795.3214},{name = 'cMain_TV_on_Windows', state = false, seq = 1553208506.6083, oseq = 1553208506.8792},{name = 'cMain_TV_on_Nvidia', state = false, seq = 1553439468.3466, oseq = 1553439722.7885},{name = 'cMain_TV_on_Chromecast', state = false, seq = 1529149426.3507, oseq = 1529149426.7496},{name = 'tAV1', state = false, seq = 1553439837.4803, oseq = 1553439838.221},{name = 'tAV2', state = false, seq = 1553440077.4342, oseq = 1553440078.5047},{name = 'tAV3', state = false, seq = 1553440785.4913, oseq = 1553440787.5199},{name = 'tAv4', state = false, seq = 1553439844.2064, oseq = 1553439844.91},{name = 'cZone2_TVHT_PS3', state = false, seq = 1547850570.0389, oseq = 1547850628.4654},{name = 'cZone2_TVHT_Windows', state = false, seq = 1547917335.1136, oseq = 1547917335.4904},{name = 'cZone2_TVHT_Nvidia', state = false, seq = 1549729803.0838, oseq = 1549729804.3337},{name = 'cZone2_TVHT_Chromecast', state = false, seq = 1529149145.1002, oseq = 1529149145.5052},{name = 'cZone2_Receiver_on', state = false, seq = 1553084733.8582, oseq = 1553084734.241},{name = 'cMain_Receiver_on', state = false, seq = 1549655971.1694, oseq = 1549655972.1049},{name = 'tReceiver_on', state = false, seq = 1553084733.8158, oseq = 1553084734.2038},{name = 'cZone2_Receiver_off', state = false, seq = 1553386858.0263, oseq = 1553386858.3279},{name = 'cMain_Receiver_off', state = false, seq = 1551554135.9543, oseq = 1551554138.6642},{name = 'tReceiver_off', state = false, seq = 1553386857.9814, oseq = 1553386858.2883},{name = 'cMain_TVHT_PS3', state = false, seq = 1529194985.2414, oseq = 1529194985.9039},{name = 'cMain_TVHT_Windows', state = false, seq = 1529195602.3083, oseq = 1529195602.9737},{name = 'cMain_TVHT_Nvidia', state = false, seq = 1532141743.1662, oseq = 1532141743.486},{name = 'cMain_TVHT_Chromecast', state = false, seq = 1529195619.7214, oseq = 1529195620.0893},{name = 'cZone2_TV_Nvidia', state = false, seq = 0, oseq = 0},{name = 'cWOL_Diskstation_Status_on', state = false, seq = 1553387147.1041, oseq = 1553401865.2017},{name = 'sSet_Zone2', state = false, seq = 1553410800.1006, oseq = 1553410800.1609},{name = 'cSet_Zone_2', state = false, seq = 1553410800.1538, oseq = 1553429141.4431},{name = 'cMain_AV3_Music', state = false, seq = 1543951640.2232, oseq = 1543951640.5402},{name = 'tGlobal_TV_off', state = true, seq = 1553440787.4343, oseq = 1553440785.6214},{name = 'cZone2_AV3_Music', state = false, seq = 1551552847.3336, oseq = 1551552847.9927},{name = 'tGlobal_Shutdown', state = false, seq = 1553315607.2838, oseq = 1553315607.5401},{name = 'cZone3_Nvidia', state = false, seq = 1551548748.7316, oseq = 1551548749.3297},{name = 'cZone3_Chromecast', state = false, seq = 0, oseq = 0},{name = 'cZone3_Receiver_off', state = false, seq = 1551554119.2085, oseq = 1551554119.494},{name = 'cZone3_Receiver_on', state = false, seq = 1551548748.7356, oseq = 1551548749.3312},{name = 'cGlobal_Receiver_off', state = false, seq = 1553315607.4734, oseq = 1553315609.4508},{name = 'tGlobal_Receiver_off', state = false, seq = 1553315607.4237, oseq = 1553315609.3992},{name = 'cPS3_Ping_on', state = false, seq = 1553169805.2422, oseq = 1553176547.1753},{name = 'tPS3_Ping_on', state = true, seq = 1549668388.7905, oseq = 0},{name = 'tPS3_Ping_off', state = false, seq = 0, oseq = 0},{name = 'pPS3_Ping', state = '0', seq = 1553176547.1256, oseq = 1553169805.1523},{name = 'cGlobal_Shutdown', state = false, seq = 1553315607.334, oseq = 1553315607.5903},{name = 'sReset_Device_Holds', state = false, seq = 1553411400.0823, oseq = 1553411400.1375},{name = 'cReset_Device_Holds', state = false, seq = 1553411400.135, oseq = 1553429141.44
75},{name = 'cMonitor_Hold_off', state = false, seq = 1553387080.392, oseq = 1553390123.5252},{name = 'tMonitor_Hold_off', state = true, seq = 1552053004.8878, oseq = 1552052998.6333},{name = 'cMonitor_off', state = false, seq = 1553387147.2911, oseq = 1553390123.8957},{name = 'tMonitor_off', state = false, seq = 1553387147.242, oseq = 1553390123.8454},{name = 'cPS3_Ping_off', state = true, seq = 1553176547.1768, oseq = 1553169805.2465},} now: {{name = 'cMatrixB1', state = true, seq = 1553440114.2145, oseq = 1553439873.2104},{name = 'cMatrixB2', state = false, seq = 1553439873.2111, oseq = 1553440114.2372},{name = 'tMatrixB1', state = false, seq = 1553380767.3161, oseq = 1553380788.5426},{name = 'tMatrixB2', state = true, seq = 1553380788.5429, oseq = 1553380767.3164},{name = 'cSony_HDMI_1_off', state = false, seq = 1553440766.051, oseq = 1553440787.6149},{name = 'cSony_HDMI_1_on', state = true, seq = 1553440787.6155, oseq = 1553440766.0537},{name = 'tSony_HDMI_1_off', state = false, seq = 1553440766.0476, oseq = 1553440787.6114},{name = 'tSony_HDMI_1_on', state = true, seq = 1553440787.6116, oseq = 1553440766.0478},{name = 'cSony_TV_off', state = false, seq = 1553441056.1753, oseq = 1553441124.9566},{name = 'pHDMI_Matrix_B1_B2', state = '1', seq = 1553380788.6327, oseq = 1553380767.4061},{name = 'cSony_TV_on_Tuner_delayed', state = false, seq = 1550661799.4074, oseq = 1550661843.7572},{name = 'cSony_TV_on', state = true, seq = 1553441124.9572, oseq = 1553441056.1988},{name = 'cSony_Tuner_HT_on', state = false, seq = 1546959402.8948, oseq = 1546966675.7579},{name = 'cSony_HT_off', state = true, seq = 1553440114.2401, oseq = 1553439873.2151},{name = 'tSony_HT_off', state = true, seq = 1553084752.7813, oseq = 1553084738.018},{name = 'tSony_HT_on', state = false, seq = 1553084738.0182, oseq = 1553084752.7816},{name = 'tSamsung_HT_on', state = false, seq = 1552841797.7367, oseq = 1552961397.6164},{name = 'tSamsung_HT_off', state = true, seq = 1552961397.6162, oseq = 1552841797.7365},{name = 'cSamsung_TV_on_Tuner_Delayed', state = false, seq = 1546954176.7697, oseq = 1546954196.1308},{name = 'cSamsung_HDMI_2_off', state = false, seq = 1546961790.4182, oseq = 1546966639.0616},{name = 'cSamsung_HDMI_2_on', state = true, seq = 1546966639.0621, oseq = 1546961790.421},{name = 'tSamsung_HDMI_2_off', state = false, seq = 1546961790.4112, oseq = 1546966639.0535},{name = 'tSamsung_HDMI_2_on', state = true, seq = 1546966639.0537, oseq = 1546961790.4115},{name = 'cSamsung_TV_off', state = true, seq = 1552239629.6414, oseq = 1552230500.6075},{name = 'cSamsung_HT_off', state = true, seq = 1553380085.2831, oseq = 1553380023.1809},{name = 'cSamsung_HT_on', state = false, seq = 1553380023.1824, oseq = 1553380085.3054},{name = 'cSamsung__TV_Tuner_HT_on', state = false, seq = 1546961790.0482, oseq = 1546966640.2229},{name = 'pSony_Windows', state = '0', seq = 1547846218.2943, oseq = 1547846199.3101},{name = 'pSony_Nvidia', state = '0', seq = 1528424516.3177, oseq = 946684997.59476},{name = 'pSony_Chromecast', state = '0', seq = 1513669538.2022, oseq = 1513669530.1802},{name = 'cSamsung_TV_on', state = false, seq = 1552230500.61, oseq = 1552239629.6459},{name = 'cSony_HT_on_TV_on', state = false, seq = 1553393134.2978, oseq = 1553393165.2779},{name = 'cSony_HT_on_TV_off', state = false, seq = 1553084746.2794, oseq = 1553084752.7932},{name = 'cNest_Home', state = false, seq = 1553129016.4267, oseq = 1553179262.051},{name = 'cNest_Away', state = true, seq = 1553179262.0513, oseq = 1553129016.4294},{name = 'tHome_mode', state = false, seq = 1553129016.4029, oseq = 1553179262.0402},{name = 'tAway_mode', state = true, seq = 1553179262.0399, oseq = 1553129016.4023},{name = 'tSamsung_Tuner', state = false, seq = 1546961790.0394, oseq = 1546966640.2148},{name = 'All_TV_HT_off', state = false, seq = 1553441056.2066, oseq = 1553441124.9959},{name = 'tSamsung_TV_on', state = false, seq = 1552230500.6002, oseq = 1552239629.6342},{name = 'tSamsung_TV_off', state = true, seq = 1552239629.634, oseq = 1552230500.6},{name = 'cSamsung_HDMI', state = true, seq =
 1546966640.2274, oseq = 1546961790.0552},{name = 'cSamsung_Antenna', state = false, seq = 1546961790.056, oseq = 1546966640.2305},{name = 'tZone2_HT_off_trigger', state = false, seq = 946685117.39239, oseq = 1528494009.1336},{name = 'cZon2_2_tuner_on', state = false, seq = 1553440765.8887, oseq = 1553440787.355},{name = 'cZone_2_tuner_off', state = true, seq = 1553440787.3554, oseq = 1553440765.8914},{name = 'tZone_2_Tuner_on', state = false, seq = 1553440765.8755, oseq = 1553440787.3409},{name = 'tZone_2_Tuner_off', state = true, seq = 1553440787.3407, oseq = 1553440765.8753},{name = 'cComputer_on', state = true, seq = 1553390123.4866, oseq = 1553387080.3198},{name = 'cComputer_off', state = false, seq = 1553387080.3205, oseq = 1553390123.4901},{name = 'tComputer_on', state = true, seq = 1553390123.4714, oseq = 1553387080.2949},{name = 'tComputer_off', state = false, seq = 1553387080.2947, oseq = 1553390123.4712},{name = 'cPing_Computer_off', state = false, seq = 1553394264.1398, oseq = 1553428754.149},{name = 'cPing_Computer_on', state = true, seq = 1553428754.1496, oseq = 1553394264.1416},{name = 'Zone_2_power', state = 'Standby', seq = 1553440114.2092, oseq = 1553439873.2076},{name = 'Zone_Main_power', state = 'Standby', seq = 1553380085.2628, oseq = 1553380023.1641},{name = 'pZone_2_Input', state = 'AV3', seq = 1553440087.062, oseq = 1553440078.3847},{name = 'pMain_Zone_input', state = 'AV3', seq = 1553440086.7357, oseq = 1553440078.058},{name = 'tSony_TV_off', state = false, seq = 1553441056.1649, oseq = 1553441124.9349},{name = 'tSony_TV_on', state = true, seq = 1553441124.9352, oseq = 1553441056.1654},{name = 'cAV1', state = false, seq = 1553385121.9746, oseq = 1553385243.9343},{name = 'pZone_2_HT_Flag', state = '0', seq = 1553084752.8749, oseq = 1553084738.0988},{name = 'cAV2', state = false, seq = 1553440078.0755, oseq = 1553440086.7533},{name = 'cAV3', state = false, seq = 1553440086.7549, oseq = 1553440114.2556},{name = 'cAV4', state = false, seq = 1553385066.5136, oseq = 1553385120.8305},{name = 'cZone_2_HT_off', state = true, seq = 1553440114.2578, oseq = 1553439873.2311},{name = 'cAV3_Main_Zone', state = true, seq = 1553440086.7584, oseq = 1553440078.0806},{name = 'pComputer_Ping', state = '1', seq = 1553428754.1324, oseq = 1553394264.1219},{name = 'cZone2_Server', state = false, seq = 1546784359.2692, oseq = 1546784389.3072},{name = 'cZone2_Tuner', state = false, seq = 1546792465.2764, oseq = 1546792470.5562},{name = 'tMain', state = true, seq = 1553445738.8281, oseq = 1553445621.1275},{name = 'tZone2', state = false, seq = 1553439911.049, oseq = 1553442408.1709},{name = 'tZone3', state = false, seq = 1553276693.79, oseq = 1553302173.3798},{name = 'cSony_PS3_on', state = false, seq = 1549743597.2601, oseq = 1549744066.7299},{name = 'tSony_PS3_on', state = false, seq = 1549743597.2376, oseq = 1549744066.6932},{name = 'tSony_PS3_off', state = true, seq = 1549744066.6925, oseq = 1549743597.2374},{name = 'cSony_TV_Ping_on', state = true, seq = 1553441168.1766, oseq = 1553441088.1646},{name = 'cSony_TV_Ping_off', state = false, seq = 1553441088.1652, oseq = 1553441168.1804},{name = 'cSamsung_TV_PIng_on', state = true, seq = 1553444192.2174, oseq = 1553443992.1763},{name = 'cSamsung_TV_PIng_off', state = false, seq = 1553443992.1769, oseq = 1553444192.2205},{name = 'pZone2_TV_Ping', state = '1', seq = 1553441168.152, oseq = 1553441088.135},{name = 'pMain_Zone_TV_Ping', state = '1', seq = 1553444192.1745, oseq = 1553443992.1216},{name = 'pWOL_Diskstation', state = '0', seq = 1553401865.1743, oseq = 1553387147.0724},{name = 'cWOL_Diskstation_Status_off', state = true, seq = 1553401865.2024, oseq = 1553387147.1059},{name = 'tTV_on', state = false, seq = 1553440785.6219, oseq = 1553440787.435},{name = 'cZone2_TV_on_PS3', state = false, seq = 1553275488.0291, oseq = 1553275488.3143},{name = 'cZone2_TV_on_Windows', state = false, seq = 1553275513.1585, oseq = 1553275513.4594},{name = 'cZone2_TV_on_Nvidia', state = false, seq = 1553440785.6493, oseq = 1553440787.4628},{name = 'cZone2_TV_Tuner', state = false, seq = 
1553442354.6517, oseq = 1553442408.2306},{name = 'tTuner_on', state = true, seq = 1553445745.1042, oseq = 1553445615.2869},{name = 'cMainTV_on_PS3', state = false, seq = 1553275898.181, oseq = 1553276801.7183},{name = 'cZone2_TV_off', state = false, seq = 1550240323.9485, oseq = 1550240324.1335},{name = 'cMain_TV_off', state = false, seq = 1552239629.5689, oseq = 1552239635.9918},{name = 'TV_off', state = false, seq = 1552239629.5382, oseq = 1552239635.9347},{name = 'cMain_TV_Tuner', state = true, seq = 1553445745.1602, oseq = 1553445615.3212},{name = 'cZone2_TV_on_Chromecast', state = false, seq = 1553170316.0324, oseq = 1553170317.0925},{name = 'cMain_TV_on_PS3', state = false, seq = 1553276717.1829, oseq = 1553276795.3214},{name = 'cMain_TV_on_Windows', state = false, seq = 1553208506.6083, oseq = 1553208506.8792},{name = 'cMain_TV_on_Nvidia', state = false, seq = 1553439468.3466, oseq = 1553439722.7885},{name = 'cMain_TV_on_Chromecast', state = false, seq = 1529149426.3507, oseq = 1529149426.7496},{name = 'tAV1', state = false, seq = 1553439837.4803, oseq = 1553439838.221},{name = 'tAV2', state = false, seq = 1553440077.4342, oseq = 1553440078.5047},{name = 'tAV3', state = false, seq = 1553440785.4913, oseq = 1553440787.5199},{name = 'tAv4', state = false, seq = 1553439844.2064, oseq = 1553439844.91},{name = 'cZone2_TVHT_PS3', state = false, seq = 1547850570.0389, oseq = 1547850628.4654},{name = 'cZone2_TVHT_Windows', state = false, seq = 1547917335.1136, oseq = 1547917335.4904},{name = 'cZone2_TVHT_Nvidia', state = false, seq = 1549729803.0838, oseq = 1549729804.3337},{name = 'cZone2_TVHT_Chromecast', state = false, seq = 1529149145.1002, oseq = 1529149145.5052},{name = 'cZone2_Receiver_on', state = false, seq = 1553084733.8582, oseq = 1553084734.241},{name = 'cMain_Receiver_on', state = false, seq = 1549655971.1694, oseq = 1549655972.1049},{name = 'tReceiver_on', state = false, seq = 1553084733.8158, oseq = 1553084734.2038},{name = 'cZone2_Receiver_off', state = false, seq = 1553386858.0263, oseq = 1553386858.3279},{name = 'cMain_Receiver_off', state = false, seq = 1551554135.9543, oseq = 1551554138.6642},{name = 'tReceiver_off', state = false, seq = 1553386857.9814, oseq = 1553386858.2883},{name = 'cMain_TVHT_PS3', state = false, seq = 1529194985.2414, oseq = 1529194985.9039},{name = 'cMain_TVHT_Windows', state = false, seq = 1529195602.3083, oseq = 1529195602.9737},{name = 'cMain_TVHT_Nvidia', state = false, seq = 1532141743.1662, oseq = 1532141743.486},{name = 'cMain_TVHT_Chromecast', state = false, seq = 1529195619.7214, oseq = 1529195620.0893},{name = 'cZone2_TV_Nvidia', state = false, seq = 0, oseq = 0},{name = 'cWOL_Diskstation_Status_on', state = false, seq = 1553387147.1041, oseq = 1553401865.2017},{name = 'sSet_Zone2', state = false, seq = 1553410800.1006, oseq = 1553410800.1609},{name = 'cSet_Zone_2', state = false, seq = 1553410800.1538, oseq = 1553429141.4431},{name = 'cMain_AV3_Music', state = false, seq = 1543951640.2232, oseq = 1543951640.5402},{name = 'tGlobal_TV_off', state = true, seq = 1553440787.4343, oseq = 1553440785.6214},{name = 'cZone2_AV3_Music', state = false, seq = 1551552847.3336, oseq = 1551552847.9927},{name = 'tGlobal_Shutdown', state = false, seq = 1553315607.2838, oseq = 1553315607.5401},{name = 'cZone3_Nvidia', state = false, seq = 1551548748.7316, oseq = 1551548749.3297},{name = 'cZone3_Chromecast', state = false, seq = 0, oseq = 0},{name = 'cZone3_Receiver_off', state = false, seq = 1551554119.2085, oseq = 1551554119.494},{name = 'cZone3_Receiver_on', state = false, seq = 1551548748.7356, oseq = 1551548749.3312},{name = 'cGlobal_Receiver_off', state = false, seq = 1553315607.4734, oseq = 1553315609.4508},{name = 'tGlobal_Receiver_off', state = false, seq = 1553315607.4237, oseq = 1553315609.3992},{name = 'cPS3_Ping_on', state = false, seq = 1553169805.2422, oseq = 1553176547.1753},{name = 'tPS3_Ping_on', state = true, seq = 1549668388.7905, oseq = 0},{name = 'tPS3_Ping_off', state = false, seq = 0, oseq = 0},{name = 'pPS3_Ping', state = '0', seq = 1553176547.1256, oseq = 15531
69805.1523},{name = 'cGlobal_Shutdown', state = false, seq = 1553315607.334, oseq = 1553315607.5903},{name = 'sReset_Device_Holds', state = false, seq = 1553411400.0823, oseq = 1553411400.1375},{name = 'cReset_Device_Holds', state = false, seq = 1553411400.135, oseq = 1553429141.4475},{name = 'cMonitor_Hold_off', state = false, seq = 1553387080.392, oseq = 1553390123.5252},{name = 'tMonitor_Hold_off', state = true, seq = 1552053004.8878, oseq = 1552052998.6333},{name = 'cMonitor_off', state = false, seq = 1553387147.2911, oseq = 1553390123.8957},{name = 'tMonitor_off', state = false, seq = 1553387147.242, oseq = 1553390123.8454},{name = 'cPS3_Ping_off', state = true, seq = 1553176547.1768, oseq = 1553169805.2465},} #hooks: 0 upnp: 0 skip: 0 v:0x11ce758/NONE duplicate:0 <0x77eae320>
50	03/24/19 12:42:25.215	luup_log:275: Reactor(debug): watch(262,"urn:dcineco-com:serviceId:MSwitch1","Status2","0","1") <0x77eae320>
50	03/24/19 12:42:25.216	luup_log:275: Reactor(debug): watch() dispatching to 290 ("TV Tuner Routine") <0x77eae320>
50	03/24/19 12:42:25.217	luup_log:275: Reactor(debug): sensorWatch(262,"urn:dcineco-com:serviceId:MSwitch1","Status2","0","1",290,275) <0x77eae320>
50	03/24/19 12:42:25.219	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x77eae320>
50	03/24/19 12:42:25.219	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.220	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.221	luup_log:275: Reactor(debug): updateSensor() base time is 1553445745(03/24/19.12:42:25) ({ hour=12, min=42, wday=1, day=24, month=3, year=2019, sec=25, yday=83, isdst=true }) <0x77eae320>
50	03/24/19 12:42:25.222	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x77eae320>
50	03/24/19 12:42:25.222	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.222	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.223	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.223	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.224	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.224	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine", laststate={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 } } <0x77eae320>
50	03/24/19 12:42:25.225	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.225	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.225	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x77eae320>
50	03/24/19 12:42:25.226	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x77eae320>
50	03/24/19 12:42:25.233	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.234	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.234	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.234	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.239	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.240	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.240	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.241	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.243	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.243	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.244	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.245	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.245	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.245	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33fwzm", new value="1" <0x77eae320>
50	03/24/19 12:42:25.246	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.246	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.248	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } <0x77eae320>
50	03/24/19 12:42:25.249	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.249	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.249	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.250	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.250	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33h5b2", new value="1" <0x77eae320>
50	03/24/19 12:42:25.250	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.251	luup_log:275: Reactor(debug): processCondition() handling "condb33h5b2" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.253	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.264	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.264	luup_log:275: Reactor(debug): processCondition() handling "grpb33dm2i" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.266	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.267	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.267	luup_log:275: Reactor(debug): processCondition() handling "grpb33c7p3" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.270	luup_log:275: Reactor(debug): watch(290,"urn:toggledbits-com:serviceId:ReactorGroup","GroupStatus_grpb33c7p3","0","1") <0x77eae320>
50	03/24/19 12:42:25.270	luup_log:275: Reactor(debug): watch() dispatching to 290 ("TV Tuner Routine") <0x77eae320>
50	03/24/19 12:42:25.270	luup_log:275: Reactor(debug): sensorWatch(290,"urn:toggledbits-com:serviceId:ReactorGroup","GroupStatus_grpb33c7p3","0","1",290,275) <0x77eae320>
50	03/24/19 12:42:25.271	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x77eae320>
50	03/24/19 12:42:25.272	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.272	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.274	luup_log:275: Reactor(debug): updateSensor() base time is 1553445745(03/24/19.12:42:25) ({ hour=12, min=42, wday=1, day=24, month=3, year=2019, sec=25, yday=83, isdst=true }) <0x77eae320>
50	03/24/19 12:42:25.274	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x77eae320>
50	03/24/19 12:42:25.274	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.275	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.275	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.276	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.276	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.277	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine", laststate={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 } } <0x77eae320>
50	03/24/19 12:42:25.277	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.278	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.278	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x77eae320>
50	03/24/19 12:42:25.278	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x77eae320>
50	03/24/19 12:42:25.286	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, operator="and" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.287	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.287	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.287	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.294	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.294	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.294	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.295	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.297	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.298	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.298	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.298	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.299	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.299	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33fwzm", new value="1" <0x77eae320>
50	03/24/19 12:42:25.299	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.300	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.302	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } <0x77eae320>
50	03/24/19 12:42:25.303	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.303	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.304	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.304	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.305	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33h5b2", new value="1" <0x77eae320>
50	03/24/19 12:42:25.305	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.305	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.306	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.306	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.307	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.308	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33c7p3" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.313	luup_log:275: Reactor(debug): evaluateGroup() process #3 in "root": { type="group", name="Main TV Ping On", id="grpb3baia5", conditions={ 1={ type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 }, 2={ type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } }, laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553445615(03/24/19.12:40:15) }, id="grpb3baia5", evalstamp=1553445615(03/24/19.12:40:15), lastvalue=false, evalstate=false, priorvalue=true, valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.313	luup_log:275: Reactor(debug): processCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.313	luup_log:275: Reactor(debug): evaluateCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.314	luup_log:275: Reactor(debug): evaluateGroup("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.316	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb3baia5": { type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.316	luup_log:275: Reactor(debug): processCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.317	luup_log:275: Reactor(debug): evaluateCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.317	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.317	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:upnp-org:serviceId:SwitchPower1"/"Status" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.318	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bb0iv", new value="1" <0x77eae320>
50	03/24/19 12:42:25.318	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bb0iv" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.318	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bb0iv" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.320	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb3baia5": { type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445615(03/24/19.12:40:15), stateedge={ 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445615(03/24/19.12:40:15), lastvalue="0", evalstate=false, priorvalue="1", valuestamp=1553445615(03/24/19.12:40:15), laststate=false }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.321	luup_log:275: Reactor(debug): processCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.321	luup_log:275: Reactor(debug): evaluateCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.322	luup_log:275: Reactor(debug): getValue("",nil,290) <0x77eae320>
50	03/24/19 12:42:25.322	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:toggledbits-com:serviceId:ReactorGroup"/"GroupStatus_grpb33c7p3" "1""istrue"""? <0x77eae320>
50	03/24/19 12:42:25.322	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bbob2", new value="1" <0x77eae320>
50	03/24/19 12:42:25.323	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bbob2" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.323	luup_log:275: Reactor(debug): processCondition() handling "condb3bbob2" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.325	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bbob2" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.325	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb3baia5" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.326	luup_log:275: Reactor(debug): processCondition() handling "grpb3baia5" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.328	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb3baia5" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.329	luup_log:275: Reactor(debug): processCondition() eval group nil cond "root" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.329	luup_log:275: Reactor(debug): processCondition() handling "root" state changed from false to true <0x77eae320>
50	03/24/19 12:42:25.331	luup_log:275: Reactor(debug): watch(290,"urn:toggledbits-com:serviceId:ReactorGroup","GroupStatus_root","0","1") <0x77eae320>
50	03/24/19 12:42:25.331	luup_log:275: Reactor(debug): watch() dispatching to 290 ("TV Tuner Routine") <0x77eae320>
50	03/24/19 12:42:25.332	luup_log:275: Reactor(debug): sensorWatch(290,"urn:toggledbits-com:serviceId:ReactorGroup","GroupStatus_root","0","1",290,275) <0x77eae320>
50	03/24/19 12:42:25.333	luup_log:275: Reactor(debug): updateSensor(290) "TV Tuner Routine" <0x77eae320>
50	03/24/19 12:42:25.333	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.334	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.336	luup_log:275: Reactor(debug): updateSensor() base time is 1553445745(03/24/19.12:42:25) ({ hour=12, min=42, wday=1, day=24, month=3, year=2019, sec=25, yday=83, isdst=true }) <0x77eae320>
50	03/24/19 12:42:25.336	luup_log:275: Reactor(debug): updateVariables(cdata,290) <0x77eae320>
50	03/24/19 12:42:25.336	luup_log:275: Reactor(debug): loadCleanState(290) <0x77eae320>
50	03/24/19 12:42:25.337	luup_log:275: Reactor(debug): loadCleanState() returning cached cstate <0x77eae320>
50	03/24/19 12:42:25.337	luup_log:275: Reactor(debug): processCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.338	luup_log:275: Reactor(debug): evaluateCondition("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.338	luup_log:275: Reactor(debug): evaluateGroup("root",nil,cdata,290) <0x77eae320>
50	03/24/19 12:42:25.339	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "root": { id="cond0", type="comment", comment="TV Tuner Routine", laststate={ id="cond0", statestamp=0, stateedge={  }, valuestamp=0 } } <0x77eae320>
50	03/24/19 12:42:25.339	luup_log:275: Reactor(debug): processCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.339	luup_log:275: Reactor(debug): evaluateCondition("cond0","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.340	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "cond0" result is state nil timer nil <0x77eae320>
50	03/24/19 12:42:25.340	luup_log:275: Reactor(debug): evaluateGroup() cond "cond0" "comment" skipped, disabled <0x77eae320>
50	03/24/19 12:42:25.348	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "root": { type="group", name="TV Main Zone", id="grpb33c7p3", conditions={ 1={ type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, operator="and" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33c7p3", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.349	luup_log:275: Reactor(debug): processCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.349	luup_log:275: Reactor(debug): evaluateCondition("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.349	luup_log:275: Reactor(debug): evaluateGroup("grpb33c7p3","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.355	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33c7p3": { type="group", name="TV Main Zone Functions", id="grpb33dm2i", conditions={ 1={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 }, 2={ type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430977(03/24/19.08:36:17), 0=1553445615(03/24/19.12:40:15) }, id="grpb33dm2i", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.356	luup_log:275: Reactor(debug): processCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.357	luup_log:275: Reactor(debug): evaluateCondition("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.357	luup_log:275: Reactor(debug): evaluateGroup("grpb33dm2i","grpb33c7p3",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.360	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445738(03/24/19.12:42:18), stateedge={ 1=1553445738(03/24/19.12:42:18), 1=1553430977(03/24/19.08:36:17), 0=1553445621(03/24/19.12:40:21) }, id="condb33fwzm", evalstamp=1553445738(03/24/19.12:42:18), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445738(03/24/19.12:42:18), laststate=true }, id="condb33fwzm", value="1", variable="Status6", devicename="MultiSwitch Global", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.360	luup_log:275: Reactor(debug): processCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.360	luup_log:275: Reactor(debug): evaluateCondition("condb33fwzm","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.361	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.361	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status6" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.362	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33fwzm", new value="1" <0x77eae320>
50	03/24/19 12:42:25.362	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33fwzm" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.362	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33fwzm" "service" final true, group now true <0x77eae320>
01	03/24/19 12:42:25.365	ZWaveNode::HandlePollUpdate_SensorMultiLevel_MeterReport stray node 36 device 250 <0x7721e520>
50	03/24/19 12:42:25.365	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb33dm2i": { type="service", device=262, service="urn:dcineco-com:serviceId:MSwitch1", operator="=", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553430965(03/24/19.08:36:05), 0=1553445615(03/24/19.12:40:15) }, id="condb33h5b2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true }, value="1", variable="Status2", devicename="MultiSwitch Global", id="condb33h5b2" } <0x77eae320>
50	03/24/19 12:42:25.366	luup_log:275: Reactor(debug): processCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.366	luup_log:275: Reactor(debug): evaluateCondition("condb33h5b2","grpb33dm2i",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.366	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.367	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:dcineco-com:serviceId:MSwitch1"/"Status2" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.367	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb33h5b2", new value="1" <0x77eae320>
50	03/24/19 12:42:25.367	luup_log:275: Reactor(debug): processCondition() eval group "grpb33dm2i" cond "condb33h5b2" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.368	luup_log:275: Reactor(debug): evaluateGroup() cond "condb33h5b2" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.368	luup_log:275: Reactor(debug): processCondition() eval group "grpb33c7p3" cond "grpb33dm2i" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.369	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33dm2i" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.369	luup_log:275: Reactor(debug): processCondition() eval group "root" cond "grpb33c7p3" result is state true timer false <0x77eae320>
50	03/24/19 12:42:25.370	luup_log:275: Reactor(debug): evaluateGroup() cond "grpb33c7p3" "group" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.376	luup_log:275: Reactor(debug): evaluateGroup() process #3 in "root": { type="group", name="Main TV Ping On", id="grpb3baia5", conditions={ 1={ type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 }, 2={ type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } }, laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553444192(03/24/19.12:16:32), 0=1553445615(03/24/19.12:40:15) }, id="grpb3baia5", evalstamp=1553445745(03/24/19.12:42:25), lastvalue=true, evalstate=true, priorvalue=false, valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, operator="and" } <0x77eae320>
50	03/24/19 12:42:25.376	luup_log:275: Reactor(debug): processCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.377	luup_log:275: Reactor(debug): evaluateCondition("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.377	luup_log:275: Reactor(debug): evaluateGroup("grpb3baia5","root",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.379	luup_log:275: Reactor(debug): evaluateGroup() process #1 in "grpb3baia5": { type="service", device=97, service="urn:upnp-org:serviceId:SwitchPower1", operator="=", laststate={ statestamp=1553444192(03/24/19.12:16:32), stateedge={ 1=1553444192(03/24/19.12:16:32), 0=1553443992(03/24/19.12:13:12) }, id="condb3bb0iv", evalstamp=1553444192(03/24/19.12:16:32), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553444192(03/24/19.12:16:32), laststate=true }, id="condb3bb0iv", value="1", variable="Status", devicename="Samsung TV Ping", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.379	luup_log:275: Reactor(debug): processCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.380	luup_log:275: Reactor(debug): evaluateCondition("condb3bb0iv","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.380	luup_log:275: Reactor(debug): getValue("1",nil,290) <0x77eae320>
50	03/24/19 12:42:25.381	luup_log:275: Reactor(debug): evaluateCondition() "service": "urn:upnp-org:serviceId:SwitchPower1"/"Status" "1""=""1"? <0x77eae320>
50	03/24/19 12:42:25.381	luup_log:275: Reactor(debug): evaluateCondition() default true exit for cond "condb3bb0iv", new value="1" <0x77eae320>
50	03/24/19 12:42:25.382	luup_log:275: Reactor(debug): processCondition() eval group "grpb3baia5" cond "condb3bb0iv" result is state true timer nil <0x77eae320>
50	03/24/19 12:42:25.382	luup_log:275: Reactor(debug): evaluateGroup() cond "condb3bb0iv" "service" final true, group now true <0x77eae320>
50	03/24/19 12:42:25.384	luup_log:275: Reactor(debug): evaluateGroup() process #2 in "grpb3baia5": { type="service", device=290, service="urn:toggledbits-com:serviceId:ReactorGroup", operator="istrue", laststate={ statestamp=1553445745(03/24/19.12:42:25), stateedge={ 1=1553445745(03/24/19.12:42:25), 1=1553444070(03/24/19.12:14:30), 0=1553445615(03/24/19.12:40:15) }, id="condb3bbob2", evalstamp=1553445745(03/24/19.12:42:25), lastvalue="1", evalstate=true, priorvalue="0", valuestamp=1553445745(03/24/19.12:42:25), laststate=true, changed=true }, id="condb3bbob2", value="", variable="GroupStatus_grpb33c7p3", devicename="TV Tuner Routine", nocase=0 } <0x77eae320>
50	03/24/19 12:42:25.385	luup_log:275: Reactor(debug): processCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
50	03/24/19 12:42:25.385	luup_log:275: Reactor(debug): evaluateCondition("condb3bbob2","grpb3baia5",cdata,290) <0x77eae320>
Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/73.0.3683.86 Safari/537.36