sendCommand sets item then item reverts

So i have a rule below that is exhibiting odd behavior and i don’t know where to start looking. The event log makes it seem like the item receives the command to change and then implements the change and then immediately reverts back.

Item

Number UHVAC_CoolSetPoint        "Air Conditioning [%.1f F]"                            <thermostat>    (All,HVAC)                   {channel="zwave:device:f46d74a7:node7:thermostat_setpoint_cooling"}

rule:

rule "Upstairs AC"
	when
		Time cron "0 0/1 * 1/1 * ? *"
	then
	//heat
	if(HVAC_Mode.state == 1){
		//check what the current upstairs temperature is
		if (UHVAC_Temperature.state >= 74){
			UHVAC_Mode.sendCommand(2)
			UHVAC_CoolSetPoint.sendCommand(73)
			
		}
		else{
			UHVAC_Mode.sendCommand(1)
			UHVAC_HeatSetPoint.sendCommand(HVAC_HeatSetPoint.state as Number)
			}
	}
		
	else{
		//air conditioning 
		if(HVAC_Mode.state == 2){
			UHVAC_Mode.sendCommand(2)
			UHVAC_CoolSetPoint.sendCommand((HVAC_CoolSetPoint.state as Number)+ 4)
			
		}
		else{
		// all other cases (off/auto set mode to match)
		UHVAC_Mode.sendCommand(HVAC_Mode.state as Number)
		UHVAC_HeatSetPoint.sendCommand(HVAC_HeatSetPoint.state as Number)
		UHVAC_CoolSetPoint.sendCommand((HVAC_CoolSetPoint.state as Number) + 4)
		
		}
	}	
	end

Event:

2018-02-15 10:57:00.009 [ome.event.ItemCommandEvent] - Item 'UHVAC_Mode' received command 2
2018-02-15 10:57:00.017 [ome.event.ItemCommandEvent] - Item 'UHVAC_CoolSetPoint' received command 82
2018-02-15 10:57:00.018 [vent.ItemStateChangedEvent] - UHVAC_CoolSetPoint changed from 83 to 82
2018-02-15 10:57:00.667 [vent.ItemStateChangedEvent] - UHVAC_CoolSetPoint changed from 82 to 83



  • Platform information:
    • Hardware: VM on ESXi
    • OS: Ubuntu 17.10
    • Java Runtime Environment: which java platform is used and what version
    • openHAB version:
Number HVAC_CoolSetPoint        "Air Conditioning [%.1f F]"                            <thermostat>    (All,HVAC,gMyOpenHAB)                   {channel="zwave:device:f46d74a7:node3:thermostat_setpoint_cooling"}

Anyone have any ideas? Its like the rule sends the command to set the item and then something triggers the system to set the item back to what it was?

Ok so i changed the log level to debugging.

Here are the event logs

2018-02-21 10:18:00.005 [ome.event.ItemCommandEvent] - Item 'UHVAC_Mode' received command 2
2018-02-21 10:18:00.009 [ome.event.ItemCommandEvent] - Item 'UHVAC_CoolSetPoint' received command 82
2018-02-21 10:18:00.016 [vent.ItemStateChangedEvent] - UHVAC_CoolSetPoint changed from 74 to 82
2018-02-21 10:18:01.074 [vent.ItemStateChangedEvent] - UHVAC_CoolSetPoint changed from 82 to 74

and here is the openhab log from the relevant times from 10:18:00 to 10:18:02

2018-02-21 10:18:00.002 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers
2018-02-21 10:18:00.002 [DEBUG] [org.quartz.core.JobRunShell         ] - Calling execute on job DEFAULT.AC_Control.rules#Upstairs AC#0 0/1 * 1/1 * ? *
2018-02-21 10:18:00.006 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=146,c=32768,r=146]={<<<event: message\nda...ommandEvent"}\n\n>>>,"type":"ThingSta...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:00.007 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=146,c=32768,r=146],false)@COMMITTED
2018-02-21 10:18:00.008 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=146,c=32768,r=146]={<<<event: message\nda...ommandEvent"}\n\n>>>,"type":"ThingSta...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.010 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.012 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 146 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,944/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.013 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 146/146+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:00.014 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.014 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@18d490bd[p=0,l=362,c=32768,r=362]={<<<event: event\ndata...d":"default"}\n\n>>>"default","pageId...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@2556df1a{null}
2018-02-21 10:18:00.014 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@11391c7a[PROCESSING][i=null,cb=Blocker@2556df1a{null}] generate: FLUSH (null,[p=0,l=362,c=32768,r=362],false)@COMMITTED
2018-02-21 10:18:00.015 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=146,l=146,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:00.015 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@230a8c68{IDLE} [DirectByteBuffer@18d490bd[p=0,l=362,c=32768,r=362]={<<<event: event\ndata...d":"default"}\n\n>>>"default","pageId...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.015 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@230a8c68{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.015 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=142,c=32768,r=142]={<<<event: message\nda...mStateEvent"}\n\n>>>"}\n\n,"type":"Thin...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:00.016 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=142,c=32768,r=142],false)@COMMITTED
2018-02-21 10:18:00.016 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 362 SelectChannelEndPoint@92017df{/0:0:0:0:0:0:0:1:53786<->8080,Open,in,out,-,W,947/30000,HttpConnection@5c05ad3b}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.017 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 362/362+0 WriteFlusher@230a8c68{WRITING}
2018-02-21 10:18:00.017 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=142,c=32768,r=142]={<<<event: message\nda...mStateEvent"}\n\n>>>"}\n\n,"type":"Thin...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.017 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@230a8c68{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.018 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@11391c7a[PROCESSING][i=null,cb=Blocker@2556df1a{null}] generate: DONE (null,[p=362,l=362,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:00.018 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.019 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 142 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,6/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.019 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 142/142+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:00.020 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.021 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=142,l=142,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:00.022 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=155,c=32768,r=155]={<<<event: message\nda...ommandEvent"}\n\n>>>ThingStatusInfoEv...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:00.022 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=155,c=32768,r=155],false)@COMMITTED
2018-02-21 10:18:00.023 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=155,c=32768,r=155]={<<<event: message\nda...ommandEvent"}\n\n>>>ThingStatusInfoEv...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.024 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.025 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 155 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,6/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.025 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 155/155+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:00.026 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.027 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=155,l=155,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:00.028 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=151,c=32768,r=151]={<<<event: message\nda...mStateEvent"}\n\n>>>"}\n\nThingStatusIn...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:00.028 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=151,c=32768,r=151],false)@COMMITTED
2018-02-21 10:18:00.029 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=151,c=32768,r=151]={<<<event: message\nda...mStateEvent"}\n\n>>>"}\n\nThingStatusIn...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.030 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.031 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 151 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,6/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.031 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 151/151+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:00.032 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.033 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=151,l=151,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:00.034 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=209,c=32768,r=209]={<<<event: message\nda...hangedEvent"}\n\n>>>p             | d...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:00.034 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=209,c=32768,r=209],false)@COMMITTED
2018-02-21 10:18:00.035 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=209,c=32768,r=209]={<<<event: message\nda...hangedEvent"}\n\n>>>p             | d...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:00.036 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:00.037 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 209 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,6/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:00.037 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 209/209+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:00.038 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:00.039 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=209,l=209,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:01.071 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=151,c=32768,r=151]={<<<event: message\nda...mStateEvent"}\n\n>>>\\",\\"oldValue\\":\\...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:01.071 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=151,c=32768,r=151],false)@COMMITTED
2018-02-21 10:18:01.072 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=151,c=32768,r=151]={<<<event: message\nda...mStateEvent"}\n\n>>>\\",\\"oldValue\\":\\...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:01.072 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@18d490bd[p=0,l=362,c=32768,r=362]={<<<event: event\ndata...d":"default"}\n\n>>>"default","pageId...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@2556df1a{null}
2018-02-21 10:18:01.072 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:01.072 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@11391c7a[PROCESSING][i=null,cb=Blocker@2556df1a{null}] generate: FLUSH (null,[p=0,l=362,c=32768,r=362],false)@COMMITTED
2018-02-21 10:18:01.072 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 151 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,1035/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:01.073 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@230a8c68{IDLE} [DirectByteBuffer@18d490bd[p=0,l=362,c=32768,r=362]={<<<event: event\ndata...d":"default"}\n\n>>>"default","pageId...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:01.073 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 151/151+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:01.073 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@230a8c68{WRITING}:IDLE-->WRITING
2018-02-21 10:18:01.073 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:01.073 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=151,l=151,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:01.073 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 362 SelectChannelEndPoint@92017df{/0:0:0:0:0:0:0:1:53786<->8080,Open,in,out,-,W,1056/30000,HttpConnection@5c05ad3b}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:01.074 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 362/362+0 WriteFlusher@230a8c68{WRITING}
2018-02-21 10:18:01.074 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@6733deb7[p=0,l=209,c=32768,r=209]={<<<event: message\nda...hangedEvent"}\n\n>>>p             | d...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@5ec09005{null}
2018-02-21 10:18:01.074 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@230a8c68{IDLE}:WRITING-->IDLE
2018-02-21 10:18:01.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@11391c7a[PROCESSING][i=null,cb=Blocker@2556df1a{null}] generate: DONE (null,[p=362,l=362,c=32768,r=0],false)@COMMITTED
2018-02-21 10:18:01.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: FLUSH (null,[p=0,l=209,c=32768,r=209],false)@COMMITTED
2018-02-21 10:18:01.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@7c723c77{IDLE} [DirectByteBuffer@6733deb7[p=0,l=209,c=32768,r=209]={<<<event: message\nda...hangedEvent"}\n\n>>>p             | d...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-02-21 10:18:01.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{WRITING}:IDLE-->WRITING
2018-02-21 10:18:01.077 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 209 SelectChannelEndPoint@14df00ea{/127.0.0.1:52386<->8080,Open,in,out,-,W,4/30000,HttpConnection@2501343e}{io=0/0,kio=0,kro=1}
2018-02-21 10:18:01.078 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - Flushed=true 209/209+0 WriteFlusher@7c723c77{WRITING}
2018-02-21 10:18:01.079 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@7c723c77{IDLE}:WRITING-->IDLE
2018-02-21 10:18:01.079 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@7703523a[PROCESSING][i=null,cb=Blocker@5ec09005{null}] generate: DONE (null,[p=209,l=209,c=32768,r=0],false)@COMMITTED

Sound similar.

Ok so in that thread it looks like it was a binding issue. Perhaps I should post about this in the zwave thread and see if any of those guys can shed some light on this?

Ok so i feel like an idiot. But after digging through my rules i finally found out what was going on. I apparently had an older rule for amazon echo integration that was resetting the value of the thermostat based set point on a different number that could be set by the echo. That number was also supposed to change to stay in sync with the main thermostat setpoint value but the order wasn’t always consistent and the rule to change the main setpoint based on the echo number was firing before the rule to change the echo number was fired.

Problem solved. Perhaps the rules engine could add a line to the events log that indicates that the command originated from a rule and identify the rule? This would have made tracking down why this command was firing easier.

I feel like an idiot but at least my problem was solved.