DEBUG 2015-08-14 13:39:42,117 [main - ] org.tango.server.ServerManager.init:227 - Starting server JDeviceForEvent/jdEvt1
DEBUG 2015-08-14 13:39:42,126 [main - ] org.tango.server.ServerManager.initPIDAndHostName:415 - pid: 5552
DEBUG 2015-08-14 13:39:42,127 [main - ] org.tango.server.ServerManager.initPIDAndHostName:416 - hostName: PC5-HP
DEBUG 2015-08-14 13:39:43,637 [main - ] o.t.server.build.DeviceClassBuilder.buildDevice:75 - create device dserver/jdeviceforevent/jdevt1 of class org.tango.server.admin.AdminDevice - in
TRACE 2015-08-14 13:39:43,638 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:77 - entry
DEBUG 2015-08-14 13:39:43,656 [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-14 13:39:43,665 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,665 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevPollStatus type java.lang.String
TRACE 2015-08-14 13:39:43,669 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,669 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,669 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryClass type java.lang.Void
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryDevice type java.lang.Void
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QuerySubDevice type java.lang.Void
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,670 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevRestart type java.lang.String
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RestartServer type java.lang.Void
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Kill type java.lang.Void
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartLogging type java.lang.Void
TRACE 2015-08-14 13:39:43,671 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopLogging type java.lang.Void
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddLoggingTarget type java.lang.String[]
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: PolledDevice type java.lang.Void
TRACE 2015-08-14 13:39:43,672 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddObjPolling type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemObjPolling type java.lang.String[]
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopPolling type java.lang.Void
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,673 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartPolling type java.lang.Void
TRACE 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardClassProperty type java.lang.String
TRACE 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,674 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardDevProperty type java.lang.String
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: LockDevice type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UnLockDevice type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ReLockDevices type java.lang.String[]
TRACE 2015-08-14 13:39:43,675 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevLockStatus type java.lang.String
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: SetLoggingLevel type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingLevel type java.lang.String[]
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,676 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingTarget type java.lang.String
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: EventSubscriptionChange type java.lang.String[]
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemoveLoggingTarget type java.lang.String[]
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,677 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,678 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UpdObjPollingPeriod type fr.esrf.Tango.DevVarLongStringArray
TRACE 2015-08-14 13:39:43,678 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,678 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,678 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ZmqEventSubscriptionChange type java.lang.String[]
TRACE 2015-08-14 13:39:43,678 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,679 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:53 - entry
DEBUG 2015-08-14 13:39:43,679 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:54 - Has an init method: init
TRACE 2015-08-14 13:39:43,684 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:65 - exit
TRACE 2015-08-14 13:39:43,687 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:64 - entry
DEBUG 2015-08-14 13:39:43,687 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:75 - Has a DeviceProperty : polling_threads_pool_size
TRACE 2015-08-14 13:39:43,687 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:100 - exit
TRACE 2015-08-14 13:39:43,688 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:63 - entry
DEBUG 2015-08-14 13:39:43,688 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status
TRACE 2015-08-14 13:39:43,689 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:92 - exit
TRACE 2015-08-14 13:39:43,689 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,689 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void
TRACE 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void
TRACE 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void
TRACE 2015-08-14 13:39:43,690 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,693 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (state)
DEBUG 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: State 0
TRACE 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (state)
TRACE 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (status)
DEBUG 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Status 0
TRACE 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (status)
TRACE 2015-08-14 13:39:43,699 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:583 - entry
TRACE 2015-08-14 13:39:43,700 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm)
DEBUG 2015-08-14 13:39:43,700 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - StateCheckAttrAlarm property value not found in tango db
DEBUG 2015-08-14 13:39:43,711 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - StateCheckAttrAlarm inject: false
DEBUG 2015-08-14 13:39:43,711 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:339 - update all attributes when reading state of status false
TRACE 2015-08-14 13:39:43,711 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,711 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period)
DEBUG 2015-08-14 13:39:43,711 [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-14 13:39:43,712 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_min_poll_period inject: []
TRACE 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period)
DEBUG 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - min_poll_period property value not found in tango db
DEBUG 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - min_poll_period inject: 0
TRACE 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,723 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period)
DEBUG 2015-08-14 13:39:43,723 [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-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_min_poll_period inject: []
TRACE 2015-08-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth)
DEBUG 2015-08-14 13:39:43,724 [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-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_poll_ring_depth inject: []
TRACE 2015-08-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,724 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth)
DEBUG 2015-08-14 13:39:43,724 [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-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_poll_ring_depth inject: []
TRACE 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr)
DEBUG 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - polled_attr property value not found in tango db
DEBUG 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:214 - polled_attr inject: []
TRACE 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,725 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polling_threads_pool_size)
DEBUG 2015-08-14 13:39:43,725 [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-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:160 - polling_threads_pool_size inject: 0
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
DEBUG 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StatusImpl.statusMachine:118 - Changing status to: Init in progress
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:107 - entry
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:112 - exit
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State)
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status)
TRACE 2015-08-14 13:39:43,726 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,727 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,728 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,729 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,730 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
DEBUG 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.doInit:566 - init OK true
DEBUG 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:594 - device init done
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:595 - exit
TRACE 2015-08-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:112 - exit
DEBUG 2015-08-14 13:39:43,731 [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-14 13:39:43,731 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.buildDevice:194 - exporting device dserver/JDeviceForEvent/jdEvt1
TRACE 2015-08-14 13:39:43,732 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (dserver/JDeviceForEvent/jdEvt1)
TRACE 2015-08-14 13:39:43,738 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit
DEBUG 2015-08-14 13:39:43,739 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:108 - loading class org.tango.jdeviceforevent.JDeviceForEvent
DEBUG 2015-08-14 13:39:43,740 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:113 - devices found  [JD/Evt/1]
DEBUG 2015-08-14 13:39:43,740 [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-14 13:39:43,740 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:77 - entry
DEBUG 2015-08-14 13:39:43,741 [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-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:53 - entry
DEBUG 2015-08-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:54 - Has an init method: initDevice
TRACE 2015-08-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:65 - exit
TRACE 2015-08-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:51 - entry
DEBUG 2015-08-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:52 - Has a delete method: deleteDevice
TRACE 2015-08-14 13:39:43,743 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:61 - exit
TRACE 2015-08-14 13:39:43,743 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:54 - entry
DEBUG 2015-08-14 13:39:43,743 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:63 - has an AroundInvoke method aroundInvoke 
TRACE 2015-08-14 13:39:43,743 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:65 - exit
TRACE 2015-08-14 13:39:43,746 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (speed)
DEBUG 2015-08-14 13:39:43,747 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Speed 0
TRACE 2015-08-14 13:39:43,747 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (speed)
TRACE 2015-08-14 13:39:43,747 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:63 - entry
DEBUG 2015-08-14 13:39:43,747 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:65 - Has a DynamicAttributeManagement : dynamicManager
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:82 - exit
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:66 - entry
DEBUG 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:81 - Has an state : state
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:100 - exit
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:63 - entry
DEBUG 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:92 - exit
TRACE 2015-08-14 13:39:43,748 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry
DEBUG 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (state)
DEBUG 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: State 0
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (state)
TRACE 2015-08-14 13:39:43,749 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:57 - entry with (status)
DEBUG 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:123 - Has an attribute: Status 0
TRACE 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:126 - exit with (status)
TRACE 2015-08-14 13:39:43,750 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:583 - entry
TRACE 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm)
DEBUG 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - StateCheckAttrAlarm property value not found in tango db
DEBUG 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:160 - StateCheckAttrAlarm inject: false
DEBUG 2015-08-14 13:39:43,750 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:339 - update all attributes when reading state of status false
TRACE 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,750 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period)
DEBUG 2015-08-14 13:39:43,750 [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-14 13:39:43,753 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_min_poll_period inject: []
TRACE 2015-08-14 13:39:43,753 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,753 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period)
DEBUG 2015-08-14 13:39:43,757 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - min_poll_period property value not found in tango db
DEBUG 2015-08-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:160 - min_poll_period inject: 0
TRACE 2015-08-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period)
DEBUG 2015-08-14 13:39:43,758 [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-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_min_poll_period inject: []
TRACE 2015-08-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,758 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth)
DEBUG 2015-08-14 13:39:43,759 [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-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - cmd_poll_ring_depth inject: []
TRACE 2015-08-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth)
DEBUG 2015-08-14 13:39:43,759 [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-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - attr_poll_ring_depth inject: []
TRACE 2015-08-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
TRACE 2015-08-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr)
DEBUG 2015-08-14 13:39:43,759 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:130 - polled_attr property value not found in tango db
DEBUG 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:214 - polled_attr inject: []
TRACE 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit
DEBUG 2015-08-14 13:39:43,760 [main - JD/Evt/1] org.tango.server.device.StateImpl.stateMachine:139 - Changing state to INIT
DEBUG 2015-08-14 13:39:43,760 [main - JD/Evt/1] org.tango.server.device.StatusImpl.statusMachine:118 - Changing status to: Init in progress
TRACE 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:118 - entry
DEBUG 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:119 - init
TRACE 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:126 - exit
TRACE 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Speed)
TRACE 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
DEBUG 2015-08-14 13:39:43,760 [main - JD/Evt/1] o.t.server.servant.PollingManager.configurePolling:133 - configure polling of Speed
DEBUG 2015-08-14 13:39:43,895 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.setPollSize:180 - polling pool size is 1
DEBUG 2015-08-14 13:39:43,931 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.startAttributePolling:251 - starting attribute Speed for polling on device JD/Evt/1
DEBUG 2015-08-14 13:39:43,932 [main - JD/Evt/1] o.tango.server.cache.AttributeCache.startRefresh:67 - start refresh cache of Speed 
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State)
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
DEBUG 2015-08-14 13:39:43,933 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status)
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:78 - exit
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:95 - entry with (isPolled)
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:104 - exit
DEBUG 2015-08-14 13:39:43,933 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.doInit:566 - init OK true
DEBUG 2015-08-14 13:39:43,933 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:594 - device init done
TRACE 2015-08-14 13:39:43,933 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:595 - exit
TRACE 2015-08-14 13:39:43,934 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:112 - exit
DEBUG 2015-08-14 13:39:43,934 [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-14 13:39:43,934 [main - JD/Evt/1] o.tango.server.export.TangoExporter.buildDevice:194 - exporting device JD/Evt/1
TRACE 2015-08-14 13:39:43,934 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (JD/Evt/1)
TRACE 2015-08-14 13:39:43,935 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit
INFO  2015-08-14 13:39:43,935 [main - JD/Evt/1] org.tango.server.ServerManager.init:243 - TANGO server JDeviceForEvent/jdEvt1 started
DEBUG 2015-08-14 13:39:43,935 [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-14 13:39:43,936 [main - JD/Evt/1] org.tango.server.ServerManager.init:246 - exit
TANGO_HOST :-> PC5-HP.ncra.tifr.res.in:20000
------- Started -------------
TRACE 2015-08-14 13:39:43,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:43,945 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:43,945 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:43,945 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:43,945 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:43,945 [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-14 13:39:43,945 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:43,946 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:43,953 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:43,953 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:43,954 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
DEBUG 2015-08-14 13:39:43,954 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateDefaultWritePart:271 - setting default value to write part
TRACE 2015-08-14 13:39:43,955 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:43,955 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:43,957 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:43,962 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:43,962 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:43,962 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:43,962 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:43,962 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:43,966 [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-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:43,966 [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-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:43,966 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:43,967 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:46,933 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:39:46,934 [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-14 13:39:46,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:46,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:46,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:46,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:46,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:46,935 [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-14 13:39:46,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:46,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:46,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:46,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:46,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:46,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:46,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:46,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:46,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:46,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:49,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:39:49,935 [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-14 13:39:49,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:49,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:49,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:49,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:49,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:49,936 [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-14 13:39:49,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:49,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:49,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:49,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:49,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:49,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:49,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:49,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:49,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:49,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:49,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:49,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:49,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:52,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:39:52,935 [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-14 13:39:52,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:52,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:52,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:52,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:52,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:52,936 [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-14 13:39:52,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:52,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:52,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:52,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:52,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:52,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:52,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:52,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:52,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:52,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:52,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:52,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:52,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:54,571 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-14 13:39:54,572 [JD/Evt/1] - Attribute adm_name requested from Monali-PC
TRACE 2015-08-14 13:39:54,572 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-14 13:39:54,704 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-14 13:39:54,705 [JD/Evt/1] - Attribute adm_name requested from Monali-PC
TRACE 2015-08-14 13:39:54,705 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-14 13:39:54,722 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:39:54,723 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:39:54,723 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:39:54,725 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:39:54,725 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:39:54,725 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,725 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:54,726 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:39:54,741 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:39:54,741 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:39:54,742 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:39:54,747 [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-14 13:39:54,747 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:39:54,747 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:39:54,748 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:118 - entry
DEBUG 2015-08-14 13:39:54,748 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:119 - client IP address is 192.168.118.207
====================== ZMQ (3.22) SERVER event system started =======================
TRACE 2015-08-14 13:39:54,751 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:188 - entry
DEBUG 2015-08-14 13:39:54,820 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:201 - bind ZMQ socket tcp://192.168.118.210:49423 for HEARTBEAT
TRACE 2015-08-14 13:39:54,823 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:217 - exit
TRACE 2015-08-14 13:39:54,823 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:188 - entry
DEBUG 2015-08-14 13:39:54,825 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:201 - bind ZMQ socket tcp://192.168.118.210:49444 for EVENTS
DEBUG 2015-08-14 13:39:54,826 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:213 - HWM has been set to 1000
TRACE 2015-08-14 13:39:54,826 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:217 - exit
TRACE 2015-08-14 13:39:54,827 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:148 - exit
TRACE 2015-08-14 13:39:54,827 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:39:54,827 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:39:54,828 [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-14 13:39:54,828 [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-14 13:39:54,828 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:39:54,828 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:39:54,828 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,828 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:54,829 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:39:54,829 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:39:54,834 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:709 - entry
REQUEST 2015-08-14 13:39:54,834 [JD/Evt/1] - Attribute adm_name requested from Monali-PC
TRACE 2015-08-14 13:39:54,834 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:711 - exit
TRACE 2015-08-14 13:39:54,906 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-14 13:39:54,906 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
DEBUG 2015-08-14 13:39:54,907 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:39:54,913 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:54,914 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:54,915 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:54,916 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:39:54,916 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:54,916 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:54,916 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-14 13:39:55,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:39:55,934 [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-14 13:39:55,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:55,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:55,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:55,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:55,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:55,936 [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-14 13:39:55,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:55,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:55,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:55,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:55,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:55,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:55,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:55,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:55,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:39:55,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:39:55,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:39:55,941 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:39:55,941 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:39:55,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:39:55,942 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:39:55,942 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:39:55,942 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:55,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:55,944 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:55,944 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:55,945 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:39:58,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:39:58,935 [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-14 13:39:58,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:58,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:58,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:58,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:39:58,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:39:58,936 [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-14 13:39:58,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:39:58,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:39:58,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:39:58,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:39:58,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:39:58,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:39:58,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:39:58,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:39:58,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:39:58,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:39:58,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:39:58,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:39:58,941 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:39:58,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:39:58,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:39:58,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:39:58,942 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:39:58,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:39:58,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:39:58,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:39:58,943 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:01,933 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:01,934 [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-14 13:40:01,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:01,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:01,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:01,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:01,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:01,935 [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-14 13:40:01,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:01,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:01,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:01,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:01,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:01,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:01,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:01,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:01,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:01,938 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:01,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:01,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:01,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:01,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:01,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:01,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:01,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:01,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:01,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:01,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:01,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:03,827 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:03,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:03,831 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:03,832 [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-14 13:40:03,832 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:40:04,933 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:04,933 [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-14 13:40:04,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:04,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:04,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:04,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:04,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:04,935 [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-14 13:40:04,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:04,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:04,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:04,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:04,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:04,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:04,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:04,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:04,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:04,938 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:04,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:04,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:04,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:04,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:04,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:04,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:04,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:04,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:04,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:04,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:04,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:07,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:07,934 [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-14 13:40:07,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:07,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:07,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:07,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:07,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:07,936 [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-14 13:40:07,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:07,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:07,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:07,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:07,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:07,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:07,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:07,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:07,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:07,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:07,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:07,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:07,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:07,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:07,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:07,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:07,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:07,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:07,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:07,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:07,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:10,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:10,935 [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-14 13:40:10,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:10,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:10,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:10,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:10,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:10,936 [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-14 13:40:10,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:10,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:10,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:10,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:10,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:10,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:10,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:10,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:10,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:10,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:10,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:10,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:10,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:10,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:10,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:10,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:10,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:10,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:10,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:10,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:10,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:12,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:12,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:12,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:12,829 [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-14 13:40:12,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:40:13,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:13,934 [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-14 13:40:13,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:13,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:13,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:13,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:13,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:13,936 [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-14 13:40:13,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:13,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:13,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:13,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:13,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:13,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:13,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:13,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:13,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:13,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:13,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:13,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:13,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:13,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:13,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:13,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:13,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:13,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:13,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:13,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:13,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,691 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-14 13:40:14,692 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from Monali-PC
TRACE 2015-08-14 13:40:14,692 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-14 13:40:14,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:14,696 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:14,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:14,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:14,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:14,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:14,698 [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-14 13:40:14,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:14,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:14,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:14,711 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:14,712 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:14,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:14,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:14,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:14,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:14,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:14,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:14,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:14,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:14,714 [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-14 13:40:14,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:14,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:14,726 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-14 13:40:14,726 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
DEBUG 2015-08-14 13:40:14,727 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:14,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,727 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:14,727 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:14,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:14,728 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-14 13:40:14,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:14,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:14,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,729 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:14,729 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:14,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,731 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
TRACE 2015-08-14 13:40:14,894 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1107 - entry
DEBUG 2015-08-14 13:40:14,895 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1113 - writing [Speed]
REQUEST 2015-08-14 13:40:14,896 [JD/Evt/1] - write_attributes_4 [Speed] from  "org.tango.console.TestClient.TestClient_Console - PID=3192"
DEBUG 2015-08-14 13:40:14,896 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:14,896 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.setAttributeValue4:90 - entry
TRACE 2015-08-14 13:40:14,896 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,896 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_WRITE_ATTRIBUTE,callType=UNKNOWN,names=[Speed]])
TRACE 2015-08-14 13:40:14,897 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,897 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,897 [RequestProcessor-5 - JD/Evt/1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:14,897 [RequestProcessor-5 - JD/Evt/1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:14,898 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.setSpeed:211 - entry
TRACE 2015-08-14 13:40:14,899 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.setSpeed:218 - exit
TRACE 2015-08-14 13:40:14,899 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:14,899 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_WRITE_ATTRIBUTE,callType=UNKNOWN,names=[Speed]])
TRACE 2015-08-14 13:40:14,899 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:14,899 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:14,900 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.setAttributeValue4:125 - exit
TRACE 2015-08-14 13:40:14,900 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1135 - exit
DEBUG 2015-08-14 13:40:16,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:16,934 [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-14 13:40:16,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:16,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:16,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:16,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:16,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:16,936 [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-14 13:40:16,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:16,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:16,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:16,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:16,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:16,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:16,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:16,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:16,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:16,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:16,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:16,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:16,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:16,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: true
TRACE 2015-08-14 13:40:16,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:16,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:120 - entry
TRACE 2015-08-14 13:40:16,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:16,942 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:219 - exit
TRACE 2015-08-14 13:40:16,944 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:134 - entry
TRACE 2015-08-14 13:40:16,944 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:16,946 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:16,946 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:140 - exit
TRACE 2015-08-14 13:40:16,946 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.cppAlignment:115 - entry
TRACE 2015-08-14 13:40:16,947 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.cppAlignment:122 - exit
DEBUG 2015-08-14 13:40:16,947 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:126 - sent event: tango://PC5-HP:20000/jd/evt/1/speed.change
TRACE 2015-08-14 13:40:16,947 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:131 - exit
TRACE 2015-08-14 13:40:16,947 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:16,947 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:16,948 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:16,948 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:16,948 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:16,948 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:19,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:19,934 [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-14 13:40:19,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:19,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:19,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:19,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:19,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:19,935 [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-14 13:40:19,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:19,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:19,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:19,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:19,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:19,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:19,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:19,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:19,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:19,938 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:19,938 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:19,938 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:19,939 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:19,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:19,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:19,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:21,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:21,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:21,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:21,830 [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-14 13:40:21,830 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:40:22,933 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:22,933 [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-14 13:40:22,933 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:22,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:22,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:22,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:22,934 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:22,935 [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-14 13:40:22,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:22,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:22,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:22,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:22,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:22,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:22,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:22,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:22,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:22,938 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:22,938 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:22,938 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:22,938 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:22,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:22,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:22,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:22,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:22,939 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:22,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:22,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:22,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,691 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-14 13:40:24,692 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from Monali-PC
TRACE 2015-08-14 13:40:24,692 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-14 13:40:24,695 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:24,696 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:24,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:24,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:24,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:24,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:24,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:24,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:24,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:24,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:24,698 [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-14 13:40:24,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:24,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:24,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:24,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:24,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:24,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:24,710 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:24,711 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:24,711 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:24,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:24,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:24,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:24,713 [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-14 13:40:24,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:24,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:24,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:24,725 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-14 13:40:24,726 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
DEBUG 2015-08-14 13:40:24,726 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:24,726 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,726 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:24,726 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:24,727 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-14 13:40:24,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:24,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:24,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:24,729 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:24,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:24,729 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:24,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:24,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:24,730 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-14 13:40:25,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:25,934 [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-14 13:40:25,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:25,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:25,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:25,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:25,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:25,936 [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-14 13:40:25,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:25,937 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:25,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:25,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:25,939 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:25,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:25,939 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:25,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:25,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:25,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:25,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:25,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:25,940 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:25,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:25,941 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:25,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:25,941 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:25,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:25,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:25,942 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:25,942 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:28,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:28,934 [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-14 13:40:28,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:28,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:28,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:28,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:28,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:28,936 [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-14 13:40:28,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:28,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:28,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:28,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:28,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:28,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:28,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:28,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:28,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:28,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:28,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:28,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:28,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:28,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:28,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:28,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:28,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:28,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:28,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:28,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:28,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:30,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:30,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:30,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:30,829 [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-14 13:40:30,830 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:40:31,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:31,934 [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-14 13:40:31,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:31,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:31,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:31,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:31,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:31,936 [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-14 13:40:31,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:31,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:31,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:31,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:31,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:31,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:31,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:31,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:31,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:31,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:31,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:31,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:31,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:31,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:31,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:31,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:31,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:31,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:31,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:31,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:31,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,692 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-14 13:40:34,693 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from Monali-PC
TRACE 2015-08-14 13:40:34,693 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-14 13:40:34,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:34,696 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:34,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:34,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:34,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:34,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:34,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:34,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:34,697 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:34,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:34,698 [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-14 13:40:34,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:34,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:34,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:34,698 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:34,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:34,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:34,711 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:34,711 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:34,711 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:34,712 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:34,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:34,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:34,713 [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-14 13:40:34,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:34,713 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:34,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:34,726 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-14 13:40:34,727 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
DEBUG 2015-08-14 13:40:34,727 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:34,727 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:34,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:34,729 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-14 13:40:34,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:34,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:34,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:34,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,731 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,731 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
TRACE 2015-08-14 13:40:34,895 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1107 - entry
DEBUG 2015-08-14 13:40:34,895 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1113 - writing [Speed]
REQUEST 2015-08-14 13:40:34,896 [JD/Evt/1] - write_attributes_4 [Speed] from  "org.tango.console.TestClient.TestClient_Console - PID=3192"
DEBUG 2015-08-14 13:40:34,896 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:34,896 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.setAttributeValue4:90 - entry
TRACE 2015-08-14 13:40:34,896 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,897 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_WRITE_ATTRIBUTE,callType=UNKNOWN,names=[Speed]])
TRACE 2015-08-14 13:40:34,897 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,897 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,897 [RequestProcessor-5 - JD/Evt/1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:34,897 [RequestProcessor-5 - JD/Evt/1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:34,898 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.setSpeed:211 - entry
TRACE 2015-08-14 13:40:34,898 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.setSpeed:218 - exit
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_WRITE_ATTRIBUTE,callType=UNKNOWN,names=[Speed]])
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.setAttributeValue4:125 - exit
TRACE 2015-08-14 13:40:34,899 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.write_attributes_4:1135 - exit
DEBUG 2015-08-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:34,934 [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-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:34,934 [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-14 13:40:34,934 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:34,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:34,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:34,935 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:34,935 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:34,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: true
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:34,936 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:120 - entry
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:219 - exit
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:134 - entry
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.marshall:140 - exit
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.cppAlignment:115 - entry
TRACE 2015-08-14 13:40:34,937 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.cppAlignment:122 - exit
DEBUG 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:126 - sent event: tango://PC5-HP:20000/jd/evt/1/speed.change
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.sendEvent:131 - exit
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:34,938 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:37,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:37,934 [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-14 13:40:37,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:37,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:37,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:37,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:37,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:37,936 [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-14 13:40:37,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:37,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:37,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:37,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:37,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:37,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:37,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:37,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:37,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:37,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:37,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:37,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:37,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:37,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:37,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:37,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:37,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:37,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:37,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:37,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:37,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:39,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:39,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:39,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:39,830 [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-14 13:40:39,830 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit
DEBUG 2015-08-14 13:40:40,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:40,934 [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-14 13:40:40,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:40,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:40,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:40,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:40,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:40,936 [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-14 13:40:40,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:40,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:40,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:40,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:40,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:40,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:40,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:40,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:40,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:40,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:40,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:40,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:40,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:40,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:40,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:40,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:40,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:40,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:40,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:40,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:40,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:43,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:43,934 [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-14 13:40:43,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:43,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:43,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:43,936 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:43,936 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:43,936 [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-14 13:40:43,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:43,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:43,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:43,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:43,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:43,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:43,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:43,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:43,939 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:43,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:43,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:43,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:43,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:43,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:43,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:43,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:43,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:43,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:43,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:43,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:43,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,695 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:698 - entry
REQUEST 2015-08-14 13:40:44,696 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from Monali-PC
TRACE 2015-08-14 13:40:44,696 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:700 - exit
TRACE 2015-08-14 13:40:44,699 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:44,700 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:44,700 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:44,700 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:44,700 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:44,700 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,701 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:44,701 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:44,701 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:44,701 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:44,701 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:44,702 [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-14 13:40:44,702 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:44,702 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:44,702 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:44,702 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:44,702 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,703 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,703 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:44,703 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:44,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1413 - entry with (ZmqEventSubscriptionChange)
REQUEST 2015-08-14 13:40:44,714 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
TRACE 2015-08-14 13:40:44,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1516 - entry
TRACE 2015-08-14 13:40:44,714 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1545 - execute command ZmqEventSubscriptionChange from DEVICE
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:88 - entry with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry
TRACE 2015-08-14 13:40:44,715 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:688 - entry
DEBUG 2015-08-14 13:40:44,716 [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-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:779 - exit
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:305 - entry
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:96 - exit with (ZmqEventSubscriptionChange)
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,716 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1552 - exit
TRACE 2015-08-14 13:40:44,717 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1438 - exit
TRACE 2015-08-14 13:40:44,727 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:986 - entry with ([speed])
REQUEST 2015-08-14 13:40:44,727 [JD/Evt/1] - read_attributes_4 [speed] from cache_device requested from Monali-PC (Java client with main class org.tango.console.TestClient.TestClient_Console - PID=3192)
DEBUG 2015-08-14 13:40:44,727 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.207:50143 - Java client with Main class org.tango.console.TestClient.TestClient_Console - PID=3192
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:44,728 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
DEBUG 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues4:201 - read from CACHE Speed
TRACE 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,729 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=CACHE_DEV,names=[Speed]])
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTES,callType=CACHE_DEV,names=[speed]])
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:44,730 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_4:1016 - exit
DEBUG 2015-08-14 13:40:46,934 [Polling thread 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed
DEBUG 2015-08-14 13:40:46,934 [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-14 13:40:46,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:46,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:46,935 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:46,935 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:46,935 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:180 - entry with (Speed)
DEBUG 2015-08-14 13:40:46,936 [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-14 13:40:46,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:197 - entry
TRACE 2015-08-14 13:40:46,936 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:203 - exit
TRACE 2015-08-14 13:40:46,937 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed)
TRACE 2015-08-14 13:40:46,937 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:138 - entry
TRACE 2015-08-14 13:40:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue4:169 - exit
TRACE 2015-08-14 13:40:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:249 - exit with (Speed)
TRACE 2015-08-14 13:40:46,938 [Polling thread 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed)
TRACE 2015-08-14 13:40:46,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:357 - entry
TRACE 2015-08-14 13:40:46,938 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:97 - entry
TRACE 2015-08-14 13:40:46,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry
TRACE 2015-08-14 13:40:46,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry
DEBUG 2015-08-14 13:40:46,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:78 - QUALITY event must send: false
TRACE 2015-08-14 13:40:46,939 [Polling thread 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:79 - exit
DEBUG 2015-08-14 13:40:46,939 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false
TRACE 2015-08-14 13:40:46,940 [Polling thread 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit
TRACE 2015-08-14 13:40:46,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushEvent:116 - exit
TRACE 2015-08-14 13:40:46,940 [Polling thread 1 - JD/Evt/1] org.tango.server.events.EventManager.pushEvent:365 - exit
TRACE 2015-08-14 13:40:46,940 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry
TRACE 2015-08-14 13:40:46,940 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:152 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]])
TRACE 2015-08-14 13:40:46,941 [Polling thread 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:158 - exit
TRACE 2015-08-14 13:40:46,941 [Polling thread 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit
TRACE 2015-08-14 13:40:48,828 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:453 - entry
TRACE 2015-08-14 13:40:48,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:213 - entry
TRACE 2015-08-14 13:40:48,829 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:219 - exit
DEBUG 2015-08-14 13:40:48,829 [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-14 13:40:48,830 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:467 - exit