DEBUG 2015-08-12 11:47:19,879 [main - ] org.tango.server.ServerManager.init:227 - Starting server JDeviceForEvent/jdEvt1
DEBUG 2015-08-12 11:47:19,914 [main - ] org.tango.server.ServerManager.initPIDAndHostName:415 - pid: 5932
DEBUG 2015-08-12 11:47:19,914 [main - ] org.tango.server.ServerManager.initPIDAndHostName:416 - hostName: PC5-HP
DEBUG 2015-08-12 11:47:22,159 [main - ] o.t.server.build.DeviceClassBuilder.buildDevice:75 - create device dserver/jdeviceforevent/jdevt1 of class org.tango.server.admin.AdminDevice - in
TRACE 2015-08-12 11:47:22,160 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:77 - entry
DEBUG 2015-08-12 11:47:22,206 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.<init>:333 - Device dserver/JDeviceForEvent/jdEvt1 of of class org.tango.server.admin.AdminDevice created with tx type: DEVICE
TRACE 2015-08-12 11:47:22,228 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,228 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: SetLoggingLevel type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-12 11:47:22,233 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,233 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,233 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingLevel type java.lang.String[]
TRACE 2015-08-12 11:47:22,233 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,233 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingTarget type java.lang.String
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevPollStatus type java.lang.String
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryClass type java.lang.Void
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,234 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryDevice type java.lang.Void
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QuerySubDevice type java.lang.Void
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevRestart type java.lang.String
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,235 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RestartServer type java.lang.Void
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Kill type java.lang.Void
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartLogging type java.lang.Void
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,236 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopLogging type java.lang.Void
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddLoggingTarget type java.lang.String[]
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: PolledDevice type java.lang.Void
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,237 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddObjPolling type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemObjPolling type java.lang.String[]
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopPolling type java.lang.Void
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,238 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartPolling type java.lang.Void
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardClassProperty type java.lang.String
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardDevProperty type java.lang.String
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,239 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: LockDevice type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UnLockDevice type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ReLockDevices type java.lang.String[]
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevLockStatus type java.lang.String
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,240 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: EventSubscriptionChange type java.lang.String[]
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemoveLoggingTarget type java.lang.String[]
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UpdObjPollingPeriod type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,241 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ZmqEventSubscriptionChange type java.lang.String[]
TRACE 2015-08-12 11:47:22,242 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,243 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:53 - entry
DEBUG 2015-08-12 11:47:22,243 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:54 - Has an init method: init
TRACE 2015-08-12 11:47:22,276 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:65 - exit
TRACE 2015-08-12 11:47:22,280 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:64 - entry
DEBUG 2015-08-12 11:47:22,280 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:75 - Has a DeviceProperty : polling_threads_pool_size
TRACE 2015-08-12 11:47:22,280 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:100 - exit
TRACE 2015-08-12 11:47:22,281 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:63 - entry
DEBUG 2015-08-12 11:47:22,281 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status
TRACE 2015-08-12 11:47:22,281 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:92 - exit
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void
TRACE 2015-08-12 11:47:22,282 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,285 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (state)
DEBUG 2015-08-12 11:47:22,318 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: State 0
TRACE 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (state)
TRACE 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (status)
DEBUG 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Status 0
TRACE 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (status)
TRACE 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:583 - entry
TRACE 2015-08-12 11:47:22,319 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm)
DEBUG 2015-08-12 11:47:22,320 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - StateCheckAttrAlarm property value not found in tango db
DEBUG 2015-08-12 11:47:22,351 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - StateCheckAttrAlarm inject: false
DEBUG 2015-08-12 11:47:22,352 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:339 - update all attributes when reading state of status false
TRACE 2015-08-12 11:47:22,352 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,352 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period)
DEBUG 2015-08-12 11:47:22,352 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - cmd_min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,352 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_min_poll_period inject: []
TRACE 2015-08-12 11:47:22,395 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,395 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period)
DEBUG 2015-08-12 11:47:22,395 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,396 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - min_poll_period inject: 0
TRACE 2015-08-12 11:47:22,396 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,396 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period)
DEBUG 2015-08-12 11:47:22,396 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - attr_min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,396 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_min_poll_period inject: []
TRACE 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth)
DEBUG 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - cmd_poll_ring_depth property value not found in tango db
DEBUG 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_poll_ring_depth inject: []
TRACE 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth)
DEBUG 2015-08-12 11:47:22,397 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - attr_poll_ring_depth property value not found in tango db
DEBUG 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_poll_ring_depth inject: []
TRACE 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr)
DEBUG 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - polled_attr property value not found in tango db
DEBUG 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - polled_attr inject: []
TRACE 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polling_threads_pool_size)
DEBUG 2015-08-12 11:47:22,398 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - polling_threads_pool_size property value not found in tango db
DEBUG 2015-08-12 11:47:22,399 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - polling_threads_pool_size inject: 0
TRACE 2015-08-12 11:47:22,399 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
DEBUG 2015-08-12 11:47:22,399 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StatusImpl.statusMachine:118 - Changing status to: Init in progress
TRACE 2015-08-12 11:47:22,399 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:107 - entry
TRACE 2015-08-12 11:47:22,399 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:112 - exit
TRACE 2015-08-12 11:47:22,403 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State)
TRACE 2015-08-12 11:47:22,403 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-12 11:47:22,404 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status)
TRACE 2015-08-12 11:47:22,404 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-12 11:47:22,404 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,405 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,406 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,407 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,410 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,410 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,410 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,410 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,410 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,411 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,412 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
DEBUG 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.doInit:566 - init OK true
DEBUG 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:594 - device init done
TRACE 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:595 - exit
TRACE 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:112 - exit
DEBUG 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] o.t.server.build.DeviceClassBuilder.buildDevice:87 - create device dserver/jdeviceforevent/jdevt1 of class org.tango.server.admin.AdminDevice - out
DEBUG 2015-08-12 11:47:22,413 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.buildDevice:194 - exporting device dserver/JDeviceForEvent/jdEvt1
TRACE 2015-08-12 11:47:22,414 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (dserver/JDeviceForEvent/jdEvt1)
TRACE 2015-08-12 11:47:22,417 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit
DEBUG 2015-08-12 11:47:22,417 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:108 - loading class org.tango.jdeviceforevent.JDeviceForEvent
DEBUG 2015-08-12 11:47:22,418 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:113 - devices found  [JD/Evt/1]
DEBUG 2015-08-12 11:47:22,418 [main - dserver/JDeviceForEvent/jdEvt1] o.t.server.build.DeviceClassBuilder.buildDevice:75 - create device jd/evt/1 of class org.tango.jdeviceforevent.JDeviceForEvent - in
TRACE 2015-08-12 11:47:22,419 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:77 - entry
DEBUG 2015-08-12 11:47:22,421 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.<init>:333 - Device JD/Evt/1 of of class org.tango.jdeviceforevent.JDeviceForEvent created with tx type: DEVICE
TRACE 2015-08-12 11:47:22,423 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:53 - entry
DEBUG 2015-08-12 11:47:22,424 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:54 - Has an init method: initDevice
TRACE 2015-08-12 11:47:22,424 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:65 - exit
TRACE 2015-08-12 11:47:22,424 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:51 - entry
DEBUG 2015-08-12 11:47:22,424 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:52 - Has a delete method: deleteDevice
TRACE 2015-08-12 11:47:22,424 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:61 - exit
TRACE 2015-08-12 11:47:22,424 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:54 - entry
DEBUG 2015-08-12 11:47:22,424 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:63 - has an AroundInvoke method aroundInvoke 
TRACE 2015-08-12 11:47:22,424 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:65 - exit
TRACE 2015-08-12 11:47:22,427 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (speed)
DEBUG 2015-08-12 11:47:22,428 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Speed 0
TRACE 2015-08-12 11:47:22,428 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (speed)
TRACE 2015-08-12 11:47:22,429 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:63 - entry
DEBUG 2015-08-12 11:47:22,429 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:65 - Has a DynamicAttributeManagement : dynamicManager
TRACE 2015-08-12 11:47:22,429 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:82 - exit
TRACE 2015-08-12 11:47:22,430 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:66 - entry
DEBUG 2015-08-12 11:47:22,430 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:81 - Has an state : state
TRACE 2015-08-12 11:47:22,430 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:100 - exit
TRACE 2015-08-12 11:47:22,430 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:63 - entry
DEBUG 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status
TRACE 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:92 - exit
TRACE 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void
TRACE 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,431 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void
TRACE 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void
TRACE 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (state)
DEBUG 2015-08-12 11:47:22,432 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: State 0
TRACE 2015-08-12 11:47:22,433 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (state)
TRACE 2015-08-12 11:47:22,433 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (status)
DEBUG 2015-08-12 11:47:22,433 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Status 0
TRACE 2015-08-12 11:47:22,433 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (status)
TRACE 2015-08-12 11:47:22,434 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:583 - entry
TRACE 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm)
DEBUG 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - StateCheckAttrAlarm property value not found in tango db
DEBUG 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:160 - StateCheckAttrAlarm inject: false
DEBUG 2015-08-12 11:47:22,434 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:339 - update all attributes when reading state of status false
TRACE 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period)
DEBUG 2015-08-12 11:47:22,434 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - cmd_min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,435 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_min_poll_period inject: []
TRACE 2015-08-12 11:47:22,435 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,435 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period)
DEBUG 2015-08-12 11:47:22,435 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,436 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:160 - min_poll_period inject: 0
TRACE 2015-08-12 11:47:22,437 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,437 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period)
DEBUG 2015-08-12 11:47:22,437 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - attr_min_poll_period property value not found in tango db
DEBUG 2015-08-12 11:47:22,437 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_min_poll_period inject: []
TRACE 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth)
DEBUG 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - cmd_poll_ring_depth property value not found in tango db
DEBUG 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_poll_ring_depth inject: []
TRACE 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth)
DEBUG 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - attr_poll_ring_depth property value not found in tango db
DEBUG 2015-08-12 11:47:22,438 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_poll_ring_depth inject: []
TRACE 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr)
DEBUG 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - polled_attr property value not found in tango db
DEBUG 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - polled_attr inject: []
TRACE 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
DEBUG 2015-08-12 11:47:22,439 [main - JD/Evt/1] org.tango.server.device.StateImpl.stateMachine:139 - Changing state to INIT
DEBUG 2015-08-12 11:47:22,439 [main - JD/Evt/1] org.tango.server.device.StatusImpl.statusMachine:118 - Changing status to: Init in progress
TRACE 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:117 - entry
DEBUG 2015-08-12 11:47:22,439 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:118 - init
TRACE 2015-08-12 11:47:22,440 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:125 - exit
TRACE 2015-08-12 11:47:22,440 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Speed)
TRACE 2015-08-12 11:47:22,440 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
DEBUG 2015-08-12 11:47:22,440 [main - JD/Evt/1] o.t.server.servant.PollingManager.configurePolling:133 - configure polling of Speed
DEBUG 2015-08-12 11:47:22,739 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.setPollSize:180 - polling pool size is 1
DEBUG 2015-08-12 11:47:22,786 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.startAttributePolling:251 - starting attribute Speed for polling on device JD/Evt/1
DEBUG 2015-08-12 11:47:22,786 [main - JD/Evt/1] o.tango.server.cache.AttributeCache.startRefresh:67 - start refresh cache of Speed 
TRACE 2015-08-12 11:47:22,787 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State)
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
DEBUG 2015-08-12 11:47:22,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status)
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
DEBUG 2015-08-12 11:47:22,788 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.doInit:566 - init OK true
DEBUG 2015-08-12 11:47:22,788 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:594 - device init done
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:595 - exit
TRACE 2015-08-12 11:47:22,788 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:112 - exit
DEBUG 2015-08-12 11:47:22,789 [main - JD/Evt/1] o.t.server.build.DeviceClassBuilder.buildDevice:87 - create device jd/evt/1 of class org.tango.jdeviceforevent.JDeviceForEvent - out
DEBUG 2015-08-12 11:47:22,789 [main - JD/Evt/1] o.tango.server.export.TangoExporter.buildDevice:194 - exporting device JD/Evt/1
TRACE 2015-08-12 11:47:22,789 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (JD/Evt/1)
TRACE 2015-08-12 11:47:22,791 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit
INFO  2015-08-12 11:47:22,791 [main - JD/Evt/1] org.tango.server.ServerManager.init:243 - TANGO server JDeviceForEvent/jdEvt1 started
DEBUG 2015-08-12 11:47:22,791 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:22,791 [main - JD/Evt/1] org.tango.server.ServerManager.init:246 - exit
------- Started -------------
TRACE 2015-08-12 11:47:22,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:22,822 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:22,846 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:22,847 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:22,848 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
DEBUG 2015-08-12 11:47:22,848 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateDefaultWritePart:271 - setting default value to write part
TRACE 2015-08-12 11:47:22,849 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:22,849 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:22,851 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:22,856 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:22,856 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:22,856 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:22,857 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:22,857 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:22,878 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:22,878 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:22,878 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:22,878 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:22,879 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:22,880 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:22,880 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:22,880 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:25,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:25,788 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:25,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:25,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:25,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:25,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:25,789 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:25,790 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:25,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:25,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:25,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:25,791 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:25,791 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:25,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:25,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:25,792 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:25,792 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:25,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:25,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:25,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:25,793 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:28,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:28,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:28,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:28,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:28,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:28,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:28,790 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:28,790 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:28,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:28,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:28,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:28,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:28,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:28,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:28,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:28,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:28,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:28,794 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:28,795 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:28,795 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:28,795 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:30,537 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-12 11:47:30,538 [JD/Evt/1] - Attribute adm_name requested from PC5-HP
TRACE 2015-08-12 11:47:30,538 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-12 11:47:30,792 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-12 11:47:30,792 [JD/Evt/1] - Attribute adm_name requested from PC5-HP
TRACE 2015-08-12 11:47:30,792 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-12 11:47:30,799 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:47:30,799 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:47:30,799 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:47:30,799 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:47:30,799 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:47:30,799 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,800 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:30,800 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:47:30,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:30,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:47:30,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:47:30,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:47:30,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:47:30,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:47:30,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:118 - entry
DEBUG 2015-08-12 11:47:30,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:119 - client IP address is 192.168.118.210
====================== ZMQ (3.22) SERVER event system started =======================
TRACE 2015-08-12 11:47:30,821 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:188 - entry
DEBUG 2015-08-12 11:47:30,877 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:201 - bind ZMQ socket tcp://192.168.118.210:50795 for HEARTBEAT
TRACE 2015-08-12 11:47:30,879 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:217 - exit
TRACE 2015-08-12 11:47:30,879 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:188 - entry
DEBUG 2015-08-12 11:47:30,881 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:201 - bind ZMQ socket tcp://192.168.118.210:50816 for EVENTS
DEBUG 2015-08-12 11:47:30,882 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:213 - HWM has been set to 1000
TRACE 2015-08-12 11:47:30,883 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:217 - exit
TRACE 2015-08-12 11:47:30,883 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:148 - exit
TRACE 2015-08-12 11:47:30,883 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:47:30,884 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:47:30,884 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.events.EventTriggerFactory.createEventTrigger:53 - create event trigger for attribute Speed of type CHANGE_EVENT
DEBUG 2015-08-12 11:47:30,884 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.<init>:66 - event trigger for Speed type is class org.tango.server.events.ChangeEventTrigger
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:47:30,885 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:47:30,888 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-12 11:47:30,888 [JD/Evt/1] - Attribute adm_name requested from PC5-HP
TRACE 2015-08-12 11:47:30,888 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-12 11:47:30,918 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-12 11:47:30,918 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
DEBUG 2015-08-12 11:47:30,926 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:50764 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=6792
TRACE 2015-08-12 11:47:30,936 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:47:30,937 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:30,938 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:30,938 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-12 11:47:31,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:31,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:31,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:31,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:31,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:31,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:31,790 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:31,790 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:31,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:31,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:31,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:31,792 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:31,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:31,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:31,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:31,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:31,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:31,794 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:31,794 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:31,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:31,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:31,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:31,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:31,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:31,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:31,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:31,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:31,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:31,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:34,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:34,788 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:34,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:34,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:34,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:34,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:34,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:34,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:34,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:34,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:34,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:34,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:34,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:34,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:34,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:34,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:34,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:34,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:34,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:34,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:34,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:34,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:34,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:34,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:34,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:34,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:34,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:34,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:34,799 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:37,788 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:37,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:37,789 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:37,789 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:37,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:37,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:37,790 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:37,790 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:37,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:37,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:37,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:37,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:37,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:37,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:37,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:37,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:37,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:37,794 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:37,794 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:37,794 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:37,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:37,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:37,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:37,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:37,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:37,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:37,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:37,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:37,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:39,884 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:47:39,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-12 11:47:39,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-12 11:47:39,889 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:464 - Heartbeat sent for tango://PC5-HP:20000/dserver/jdeviceforevent/jdevt1.heartbeat
TRACE 2015-08-12 11:47:39,889 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:47:40,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:40,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:40,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:40,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:40,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:40,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:40,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:40,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:40,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:40,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:40,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:40,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:40,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:40,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:40,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:40,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:40,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:40,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:40,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:40,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:40,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:40,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:40,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:40,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:40,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:40,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:40,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:40,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:40,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:43,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:43,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:43,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:43,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:43,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:43,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:43,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:43,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:43,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:43,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:43,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:43,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:43,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:43,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:43,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:43,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:43,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:43,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:43,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:43,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:43,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:43,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:43,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:43,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:43,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:43,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:43,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:43,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:43,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:46,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:46,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:46,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:46,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:46,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:46,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:46,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:46,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:46,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:46,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:46,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:46,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:46,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:46,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:46,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:46,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:46,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:46,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:46,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:46,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:46,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:46,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:46,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:46,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:46,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:46,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:46,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:46,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:46,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:48,885 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:47:48,885 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-12 11:47:48,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-12 11:47:48,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:464 - Heartbeat sent for tango://PC5-HP:20000/dserver/jdeviceforevent/jdevt1.heartbeat
TRACE 2015-08-12 11:47:48,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:47:49,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:49,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:49,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:49,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:49,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:49,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:49,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:49,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:49,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:49,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:49,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:49,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:49,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:49,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:49,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:49,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:49,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:49,794 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:49,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:49,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:49,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:49,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:49,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:49,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:49,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:49,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:49,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:49,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:49,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,786 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-12 11:47:50,787 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP
TRACE 2015-08-12 11:47:50,787 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-12 11:47:50,791 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:47:50,791 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:47:50,791 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:47:50,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:50,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:47:50,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:47:50,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:47:50,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:47:50,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:47:50,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:47:50,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:50,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:47:50,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:47:50,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:47:50,811 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:47:50,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:47:50,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:47:50,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:47:50,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:47:50,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:47:50,822 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-12 11:47:50,822 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
DEBUG 2015-08-12 11:47:50,822 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:50764 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=6792
TRACE 2015-08-12 11:47:50,822 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,822 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-12 11:47:50,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:50,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:50,825 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-12 11:47:52,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:52,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:52,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:52,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:52,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:52,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:52,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:52,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:52,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:52,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:52,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:52,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:52,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:52,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:52,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:52,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:52,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:52,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:52,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:52,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:52,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:52,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:52,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:52,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:52,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:52,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:52,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:52,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:52,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:47:55,789 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:55,789 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:55,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:55,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:55,790 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:55,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:55,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:55,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:55,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:55,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:55,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:55,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:55,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:55,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:55,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:55,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:55,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:55,794 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:55,794 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:55,794 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:55,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:55,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:55,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:55,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:55,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:55,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:55,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:55,796 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:55,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:57,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:47:57,887 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-12 11:47:57,887 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-12 11:47:57,889 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:464 - Heartbeat sent for tango://PC5-HP:20000/dserver/jdeviceforevent/jdevt1.heartbeat
TRACE 2015-08-12 11:47:57,890 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:47:58,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:47:58,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:47:58,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:58,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:58,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:58,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:47:58,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:47:58,792 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:47:58,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:47:58,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:47:58,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:47:58,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:47:58,796 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:47:58,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:47:58,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:47:58,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:47:58,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:47:58,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:47:58,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:47:58,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:47:58,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:47:58,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:47:58,798 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:47:58,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:47:58,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:47:58,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:47:58,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:47:58,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:47:58,799 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,787 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-12 11:48:00,787 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP
TRACE 2015-08-12 11:48:00,788 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-12 11:48:00,791 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:48:00,792 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:00,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:00,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:00,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:00,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:00,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:00,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:00,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:00,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:00,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:00,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:00,807 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:48:00,807 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:00,808 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:00,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:00,819 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-12 11:48:00,819 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
DEBUG 2015-08-12 11:48:00,819 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:50764 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=6792
TRACE 2015-08-12 11:48:00,819 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,819 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:00,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:00,821 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-12 11:48:01,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:01,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:01,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:01,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:01,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:01,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:01,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:01,792 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:01,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:01,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:01,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:01,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:01,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:01,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:01,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:01,794 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:01,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:01,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:01,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:01,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:01,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:01,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:01,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:01,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:01,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:01,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:01,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:01,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:01,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:04,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:04,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:04,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:04,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:04,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:04,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:04,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:04,792 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:04,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:04,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:04,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:04,795 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:04,795 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:04,795 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:04,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:04,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:04,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:04,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:04,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:04,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:04,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:04,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:04,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:04,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:04,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:04,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:04,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:04,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:04,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:06,886 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:48:06,887 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-12 11:48:06,887 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-12 11:48:06,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:464 - Heartbeat sent for tango://PC5-HP:20000/dserver/jdeviceforevent/jdevt1.heartbeat
TRACE 2015-08-12 11:48:06,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:48:07,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:07,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:07,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:07,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:07,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:07,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:07,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:07,792 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:07,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:07,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:07,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:07,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:07,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:07,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:07,795 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:07,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:07,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:07,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:07,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:07,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:07,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:07,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:07,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:07,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:07,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:07,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:07,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:07,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:07,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,788 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-12 11:48:10,788 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP
TRACE 2015-08-12 11:48:10,788 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
DEBUG 2015-08-12 11:48:10,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:10,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:10,790 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:10,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
TRACE 2015-08-12 11:48:10,791 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
DEBUG 2015-08-12 11:48:10,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
REQUEST 2015-08-12 11:48:10,792 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:10,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:10,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:10,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:10,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:10,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:10,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:10,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
TRACE 2015-08-12 11:48:10,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
DEBUG 2015-08-12 11:48:10,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:10,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:10,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:10,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:10,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:10,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:10,795 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:10,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:10,796 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,796 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,796 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:10,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:10,796 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:10,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:10,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:10,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:10,799 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:10,799 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:10,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,799 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:48:10,810 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:10,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:10,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:10,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:10,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:10,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:10,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-12 11:48:10,823 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
DEBUG 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:50764 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=6792
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,823 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:10,824 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:10,825 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:10,825 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:10,825 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-12 11:48:13,790 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:13,790 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:13,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:13,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:13,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:13,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:13,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:13,792 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:13,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:13,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:13,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:13,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:13,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:13,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:13,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:13,795 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:13,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:13,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:13,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:13,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:13,796 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:13,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:13,797 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:13,797 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:15,887 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-12 11:48:15,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-12 11:48:15,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-12 11:48:15,888 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:464 - Heartbeat sent for tango://PC5-HP:20000/dserver/jdeviceforevent/jdevt1.heartbeat
TRACE 2015-08-12 11:48:15,889 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-12 11:48:16,791 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:16,791 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:16,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:16,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:16,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:16,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:16,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:16,793 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:16,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:16,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:16,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:16,794 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:16,795 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:16,795 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:16,795 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:16,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:16,795 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:16,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:16,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:16,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:16,796 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:16,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:16,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:16,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:16,797 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:16,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:16,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:16,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:16,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:19,791 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:19,791 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:19,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:19,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:19,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:19,792 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:19,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:19,793 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:19,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:19,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:19,794 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:19,795 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:19,795 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:19,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:19,796 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:19,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:19,796 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:19,796 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:19,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:19,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:19,797 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:19,797 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:19,798 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:19,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:19,798 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:19,798 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:19,798 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:19,799 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:19,799 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,787 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-12 11:48:20,788 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP
TRACE 2015-08-12 11:48:20,788 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-12 11:48:20,791 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:48:20,792 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:20,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:20,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:20,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:20,792 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:20,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:20,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:20,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:20,793 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:20,794 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:20,795 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:20,809 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-12 11:48:20,810 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
TRACE 2015-08-12 11:48:20,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-12 11:48:20,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-12 11:48:20,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-12 11:48:20,810 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:20,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-12 11:48:20,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:20,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-12 11:48:20,811 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:774 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT
TRACE 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-12 11:48:20,812 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-12 11:48:20,813 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-12 11:48:20,819 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-12 11:48:20,820 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from PC5-HP (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=6792)
DEBUG 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:50764 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=6792
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-12 11:48:20,820 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:20,821 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed 
TRACE 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-12 11:48:22,791 [Polling thread 1 - JD/Evt/1] o.t.s.a.ReflectAttributeBehavior.getValue:84 - read attribute Speed from method 'public double org.tango.jdeviceforevent.JDeviceForEvent.getSpeed()'
TRACE 2015-08-12 11:48:22,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry
TRACE 2015-08-12 11:48:22,792 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit
TRACE 2015-08-12 11:48:22,792 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-12 11:48:22,793 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit
TRACE 2015-08-12 11:48:22,794 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit