DEBUG 2015-08-10 12:40:09,159 [main - ] org.tango.server.ServerManager.init:247 - Starting server JDeviceForEvent/jdEvt1 DEBUG 2015-08-10 12:40:09,163 [main - ] org.tango.server.ServerManager.initPIDAndHostName:435 - pid: 5408 DEBUG 2015-08-10 12:40:09,164 [main - ] org.tango.server.ServerManager.initPIDAndHostName:436 - hostName: PC5-HP.ncra.tifr.res.in DEBUG 2015-08-10 12:40:10,514 [main - ] o.t.server.build.DeviceClassBuilder.buildDevice:75 - create device dserver/jdeviceforevent/jdevt1 of class org.tango.server.admin.AdminDevice TRACE 2015-08-10 12:40:10,514 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:85 - entry DEBUG 2015-08-10 12:40:10,533 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.:353 - Device dserver/JDeviceForEvent/jdEvt1 of of class org.tango.server.admin.AdminDevice created with tx type: DEVICE TRACE 2015-08-10 12:40:10,538 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:58 - entry with (status) DEBUG 2015-08-10 12:40:10,546 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:125 - Has an attribute: Status 0 TRACE 2015-08-10 12:40:10,547 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:128 - exit with (status) TRACE 2015-08-10 12:40:10,547 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:58 - entry with (state) DEBUG 2015-08-10 12:40:10,547 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:125 - Has an attribute: State 0 TRACE 2015-08-10 12:40:10,547 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeFieldBuilder.build:128 - exit with (state) TRACE 2015-08-10 12:40:10,547 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,548 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void TRACE 2015-08-10 12:40:10,549 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void TRACE 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void TRACE 2015-08-10 12:40:10,550 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingTarget type java.lang.String TRACE 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: PolledDevice type java.lang.Void TRACE 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,557 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ReLockDevices type java.lang.String[] TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardDevProperty type java.lang.String TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UnLockDevice type fr.esrf.Tango.DevVarLongStringArray TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartPolling type java.lang.Void TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,558 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddLoggingTarget type java.lang.String[] TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryWizardClassProperty type java.lang.String TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: ZmqEventSubscriptionChange type java.lang.String[] TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevLockStatus type java.lang.String TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: GetLoggingLevel type java.lang.String[] TRACE 2015-08-10 12:40:10,559 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryDevice type java.lang.Void TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemObjPolling type java.lang.String[] TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopPolling type java.lang.Void TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RemoveLoggingTarget type java.lang.String[] TRACE 2015-08-10 12:40:10,560 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: RestartServer type java.lang.Void TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StopLogging type java.lang.Void TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Kill type java.lang.Void TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: AddObjPolling type fr.esrf.Tango.DevVarLongStringArray TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,561 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevPollStatus type java.lang.String TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: LockDevice type fr.esrf.Tango.DevVarLongStringArray TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: DevRestart type java.lang.String TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: StartLogging type java.lang.Void TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QueryClass type java.lang.Void TRACE 2015-08-10 12:40:10,562 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: EventSubscriptionChange type java.lang.String[] TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: QuerySubDevice type java.lang.Void TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: SetLoggingLevel type fr.esrf.Tango.DevVarLongStringArray TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:89 - Has a command: UpdObjPollingPeriod type fr.esrf.Tango.DevVarLongStringArray TRACE 2015-08-10 12:40:10,563 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,564 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:53 - entry DEBUG 2015-08-10 12:40:10,564 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:54 - Has an init method: init TRACE 2015-08-10 12:40:10,567 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.InitBuilder.build:65 - exit TRACE 2015-08-10 12:40:10,567 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:64 - entry DEBUG 2015-08-10 12:40:10,568 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:75 - Has a DeviceProperty : polling_threads_pool_size TRACE 2015-08-10 12:40:10,568 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.DevicePropertyBuilder.build:100 - exit TRACE 2015-08-10 12:40:10,569 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:63 - entry DEBUG 2015-08-10 12:40:10,569 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status TRACE 2015-08-10 12:40:10,569 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.build.AttributeMethodBuilder.build:96 - exit TRACE 2015-08-10 12:40:10,569 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:648 - entry TRACE 2015-08-10 12:40:10,569 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm) DEBUG 2015-08-10 12:40:10,570 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - StateCheckAttrAlarm property value not found in tango db DEBUG 2015-08-10 12:40:10,580 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:232 - StateCheckAttrAlarm inject: true DEBUG 2015-08-10 12:40:10,580 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:359 - update all attributes when reading state of status true TRACE 2015-08-10 12:40:10,580 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,580 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period) DEBUG 2015-08-10 12:40:10,580 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - cmd_min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,581 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:300 - cmd_min_poll_period inject: [] TRACE 2015-08-10 12:40:10,584 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,584 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period) DEBUG 2015-08-10 12:40:10,584 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:232 - min_poll_period inject: 0 TRACE 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period) DEBUG 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - attr_min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:300 - attr_min_poll_period inject: [] TRACE 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth) DEBUG 2015-08-10 12:40:10,585 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - cmd_poll_ring_depth property value not found in tango db DEBUG 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:300 - cmd_poll_ring_depth inject: [] TRACE 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth) DEBUG 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - attr_poll_ring_depth property value not found in tango db DEBUG 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:300 - attr_poll_ring_depth inject: [] TRACE 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr) DEBUG 2015-08-10 12:40:10,586 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - polled_attr property value not found in tango db DEBUG 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectArray:300 - polled_attr inject: [] TRACE 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polling_threads_pool_size) DEBUG 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - polling_threads_pool_size property value not found in tango db DEBUG 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.PropertiesUtils.injectValue:232 - polling_threads_pool_size inject: 0 TRACE 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.properties.DevicePropertyImpl.update:67 - exit DEBUG 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StatusImpl.statusMachine:119 - Changing status to: Init in progress TRACE 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:109 - entry TRACE 2015-08-10 12:40:10,587 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.init:114 - exit TRACE 2015-08-10 12:40:10,588 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status) TRACE 2015-08-10 12:40:10,588 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:66 - exit TRACE 2015-08-10 12:40:10,588 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State) TRACE 2015-08-10 12:40:10,588 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:66 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,589 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,590 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,591 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,592 [main - dserver/JDeviceForEvent/jdEvt1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit DEBUG 2015-08-10 12:40:10,593 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.doInit:631 - end with isCorrectlyInit=true TRACE 2015-08-10 12:40:10,593 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_list_query_2:1433 - entry REQUEST 2015-08-10 12:40:10,593 [dserver/JDeviceForEvent/jdEvt1] - command_list_query_2 requested from DEBUG 2015-08-10 12:40:10,593 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_list_query_2:1451 - found 31 commands TRACE 2015-08-10 12:40:10,593 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_list_query_2:1452 - exit TRACE 2015-08-10 12:40:10,594 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1751 - entry with ([All attributes]) REQUEST 2015-08-10 12:40:10,594 [dserver/JDeviceForEvent/jdEvt1] - get_attribute_config_5 [All attributes] requested from DEBUG 2015-08-10 12:40:10,594 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1763 - get All TRACE 2015-08-10 12:40:10,596 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1789 - exit DEBUG 2015-08-10 12:40:10,597 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:665 - device init done TRACE 2015-08-10 12:40:10,597 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.initDevice:666 - exit TRACE 2015-08-10 12:40:10,597 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.build.DeviceBuilder.createDevice:127 - exit DEBUG 2015-08-10 12:40:10,597 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.buildDevice:230 - exporting device dserver/JDeviceForEvent/jdEvt1 TRACE 2015-08-10 12:40:10,597 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (dserver/JDeviceForEvent/jdEvt1) TRACE 2015-08-10 12:40:10,600 [main - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit DEBUG 2015-08-10 12:40:10,601 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:108 - loading class org.tango.jdeviceforevent.JDeviceForEvent DEBUG 2015-08-10 12:40:10,602 [main - dserver/JDeviceForEvent/jdEvt1] o.tango.server.export.TangoExporter.exportDevices:113 - devices found [JD/Evt/1] DEBUG 2015-08-10 12:40:10,602 [main - dserver/JDeviceForEvent/jdEvt1] o.t.server.build.DeviceClassBuilder.buildDevice:75 - create device jd/evt/1 of class org.tango.jdeviceforevent.JDeviceForEvent TRACE 2015-08-10 12:40:10,602 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:85 - entry DEBUG 2015-08-10 12:40:10,603 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.:353 - Device JD/Evt/1 of of class org.tango.jdeviceforevent.JDeviceForEvent created with tx type: DEVICE TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:58 - entry with (status) DEBUG 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:125 - Has an attribute: Status 0 TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:128 - exit with (status) TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:58 - entry with (state) DEBUG 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:125 - Has an attribute: State 0 TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:128 - exit with (state) TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Status type java.lang.Void TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,603 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,604 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: State type java.lang.Void TRACE 2015-08-10 12:40:10,604 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,604 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:65 - entry DEBUG 2015-08-10 12:40:10,604 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:89 - Has a command: Init type java.lang.Void TRACE 2015-08-10 12:40:10,604 [main - JD/Evt/1] o.tango.server.build.CommandBuilder.build:104 - exit TRACE 2015-08-10 12:40:10,608 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:74 - entry DEBUG 2015-08-10 12:40:10,608 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:76 - Has a DynamicAttributeManagement : dynamicManager TRACE 2015-08-10 12:40:10,608 [main - JD/Evt/1] o.t.s.build.DynamicManagerBuilder.build:102 - exit TRACE 2015-08-10 12:40:10,608 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:58 - entry with (speed) DEBUG 2015-08-10 12:40:10,609 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:125 - Has an attribute: Speed 0 TRACE 2015-08-10 12:40:10,609 [main - JD/Evt/1] o.t.s.build.AttributeFieldBuilder.build:128 - exit with (speed) TRACE 2015-08-10 12:40:10,609 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:53 - entry DEBUG 2015-08-10 12:40:10,609 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:54 - Has an init method: initDevice TRACE 2015-08-10 12:40:10,609 [main - JD/Evt/1] org.tango.server.build.InitBuilder.build:65 - exit TRACE 2015-08-10 12:40:10,609 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:51 - entry DEBUG 2015-08-10 12:40:10,610 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:52 - Has a delete method: deleteDevice TRACE 2015-08-10 12:40:10,610 [main - JD/Evt/1] org.tango.server.build.DeleteBuilder.build:61 - exit TRACE 2015-08-10 12:40:10,610 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:54 - entry DEBUG 2015-08-10 12:40:10,610 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:63 - has an AroundInvoke method aroundInvoke TRACE 2015-08-10 12:40:10,616 [main - JD/Evt/1] o.t.server.build.AroundInvokeBuilder.build:65 - exit TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:66 - entry DEBUG 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:81 - Has an state : state TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:103 - exit TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:63 - entry DEBUG 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:78 - Has an status : status TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.build.AttributeMethodBuilder.build:96 - exit TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:648 - entry TRACE 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (StateCheckAttrAlarm) DEBUG 2015-08-10 12:40:10,617 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - StateCheckAttrAlarm property value not found in tango db DEBUG 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:232 - StateCheckAttrAlarm inject: true DEBUG 2015-08-10 12:40:10,618 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.setStateCheckAttrAlarm:359 - update all attributes when reading state of status true TRACE 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_min_poll_period) DEBUG 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - cmd_min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:300 - cmd_min_poll_period inject: [] TRACE 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,618 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (min_poll_period) DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectValue:232 - min_poll_period inject: 0 TRACE 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_min_poll_period) DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - attr_min_poll_period property value not found in tango db DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:300 - attr_min_poll_period inject: [] TRACE 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (cmd_poll_ring_depth) DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - cmd_poll_ring_depth property value not found in tango db DEBUG 2015-08-10 12:40:10,619 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:300 - cmd_poll_ring_depth inject: [] TRACE 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (attr_poll_ring_depth) DEBUG 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - attr_poll_ring_depth property value not found in tango db DEBUG 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:300 - attr_poll_ring_depth inject: [] TRACE 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit TRACE 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:61 - entry with (polled_attr) DEBUG 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.getDeviceProperty:179 - polled_attr property value not found in tango db DEBUG 2015-08-10 12:40:10,620 [main - JD/Evt/1] o.t.s.properties.PropertiesUtils.injectArray:300 - polled_attr inject: [] TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.properties.DevicePropertyImpl.update:67 - exit DEBUG 2015-08-10 12:40:10,621 [main - JD/Evt/1] org.tango.server.device.StateImpl.stateMachine:140 - Changing state to INIT DEBUG 2015-08-10 12:40:10,621 [main - JD/Evt/1] org.tango.server.device.StatusImpl.statusMachine:119 - Changing status to: Init in progress TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:117 - entry DEBUG 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:118 - init TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.initDevice:125 - exit TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Status) TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:66 - exit TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (State) TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:66 - exit TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:65 - entry with (Speed) TRACE 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertiesFromDB:66 - exit DEBUG 2015-08-10 12:40:10,621 [main - JD/Evt/1] o.t.server.servant.PollingManager.configurePolling:134 - configure polling of Speed DEBUG 2015-08-10 12:40:10,668 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.setPollSize:180 - polling pool size is 1 DEBUG 2015-08-10 12:40:10,682 [main - JD/Evt/1] o.t.server.cache.TangoCacheManager.startAttributePolling:251 - starting attribute Speed for polling on device JD/Evt/1 DEBUG 2015-08-10 12:40:10,682 [main - JD/Evt/1] o.tango.server.cache.AttributeCache.startRefresh:67 - start refresh cache of Speed TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit DEBUG 2015-08-10 12:40:10,683 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:108 - entry with (isPolled) TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] o.t.s.p.AttributePropertiesManager.getAttributePropertyFromDB:117 - exit DEBUG 2015-08-10 12:40:10,683 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.doInit:631 - end with isCorrectlyInit=true TRACE 2015-08-10 12:40:10,683 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.command_list_query_2:1433 - entry REQUEST 2015-08-10 12:40:10,683 [JD/Evt/1] - command_list_query_2 requested from DEBUG 2015-08-10 12:40:10,684 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.command_list_query_2:1451 - found 3 commands TRACE 2015-08-10 12:40:10,684 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.command_list_query_2:1452 - exit TRACE 2015-08-10 12:40:10,684 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1751 - entry with ([All attributes]) REQUEST 2015-08-10 12:40:10,684 [JD/Evt/1] - get_attribute_config_5 [All attributes] requested from DEBUG 2015-08-10 12:40:10,684 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1763 - get All TRACE 2015-08-10 12:40:10,684 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.get_attribute_config_5:1789 - exit DEBUG 2015-08-10 12:40:10,684 [main - JD/Evt/1] o.t.s.e.DeviceInterfaceChangedSender.pushEvent:99 - send event for interface changed of JD/Evt/1 TRACE 2015-08-10 12:40:10,686 [main - JD/Evt/1] org.tango.server.events.EventManager.pushInterfaceChangedEvent:525 - entry DEBUG 2015-08-10 12:40:10,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:10,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:10,691 [main - JD/Evt/1] org.tango.server.events.EventManager.pushInterfaceChangedEvent:531 - exit DEBUG 2015-08-10 12:40:10,691 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:665 - device init done TRACE 2015-08-10 12:40:10,691 [main - JD/Evt/1] org.tango.server.servant.DeviceImpl.initDevice:666 - exit TRACE 2015-08-10 12:40:10,691 [main - JD/Evt/1] org.tango.server.build.DeviceBuilder.createDevice:127 - exit DEBUG 2015-08-10 12:40:10,691 [main - JD/Evt/1] o.tango.server.export.TangoExporter.buildDevice:230 - exporting device JD/Evt/1 TRACE 2015-08-10 12:40:10,691 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:75 - entry with (JD/Evt/1) TRACE 2015-08-10 12:40:10,693 [main - JD/Evt/1] org.tango.server.servant.ORBUtils.exportDeviceWithDatabase:97 - exit INFO 2015-08-10 12:40:10,693 [main - JD/Evt/1] org.tango.server.ServerManager.init:263 - TANGO server JDeviceForEvent/jdEvt1 started TRACE 2015-08-10 12:40:10,694 [main - JD/Evt/1] org.tango.server.ServerManager.init:266 - exit ------- Started ------------- TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:10,697 [Polling 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-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:10,697 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:10,702 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit DEBUG 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateDefaultWritePart:273 - setting default value to write part TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:10,703 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:10,704 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:10,704 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:10,704 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:10,707 [Polling 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-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:10,707 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:10,708 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:13,683 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:13,683 [Polling 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-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:13,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:16,684 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:16,684 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:16,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:16,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:16,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:16,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:16,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:16,686 [Polling 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-10 12:40:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:16,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:16,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:16,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:16,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:16,689 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:16,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:16,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:16,689 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:16,689 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:16,690 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:16,690 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:19,685 [Polling 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-10 12:40:19,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:19,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:20,087 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:793 - entry REQUEST 2015-08-10 12:40:20,087 [JD/Evt/1] - Attribute adm_name requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:40:20,087 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:795 - exit TRACE 2015-08-10 12:40:20,165 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:793 - entry REQUEST 2015-08-10 12:40:20,165 [JD/Evt/1] - Attribute adm_name requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:40:20,165 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:795 - exit TRACE 2015-08-10 12:40:20,174 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:40:20,174 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:40:20,174 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:40:20,175 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:40:20,175 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:40:20,175 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:40:20,175 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:20,175 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:20,179 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:20,179 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:40:20,179 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:40:20,181 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:40:20,181 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:40:20,181 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry TRACE 2015-08-10 12:40:20,181 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:124 - entry DEBUG 2015-08-10 12:40:20,181 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:125 - client IP address is 192.168.118.210 ====================== ZMQ (3.22) SERVER event system started ======================= TRACE 2015-08-10 12:40:20,182 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:194 - entry DEBUG 2015-08-10 12:40:20,247 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:207 - bind ZMQ socket tcp://192.168.118.210:51925 for HEARTBEAT TRACE 2015-08-10 12:40:20,250 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:223 - exit TRACE 2015-08-10 12:40:20,251 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:194 - entry DEBUG 2015-08-10 12:40:20,253 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:207 - bind ZMQ socket tcp://192.168.118.210:51946 for EVENTS DEBUG 2015-08-10 12:40:20,254 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:219 - HWM has been set to 1000 TRACE 2015-08-10 12:40:20,254 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.setEndpoints:223 - exit TRACE 2015-08-10 12:40:20,255 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.initialize:154 - exit TRACE 2015-08-10 12:40:20,255 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:40:20,255 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:40:20,256 [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-10 12:40:20,256 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.:70 - event trigger for Speed type is class org.tango.server.events.ChangeEventTrigger DEBUG 2015-08-10 12:40:20,256 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:40:20,256 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:40:20,257 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:40:20,260 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:793 - entry REQUEST 2015-08-10 12:40:20,260 [JD/Evt/1] - Attribute adm_name requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:40:20,260 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.adm_name:795 - exit TRACE 2015-08-10 12:40:20,286 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:40:20,286 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:40:20,287 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:40:20,289 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:40:20,289 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:20,289 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:20,289 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:22,684 [Polling 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-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:22,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:22,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:22,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:22,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:25,684 [Polling 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-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:25,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:25,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:25,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:28,684 [Polling 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-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:28,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:28,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:28,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:28,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:28,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:29,256 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:40:29,256 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:40:29,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:40:29,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:40:29,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:31,684 [Polling 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-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:31,684 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:31,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:31,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:31,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:34,685 [Polling 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-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:34,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:34,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:37,685 [Polling 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-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:37,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:37,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:38,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:40:38,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:40:38,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:40:38,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:40:38,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit TRACE 2015-08-10 12:40:40,147 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:782 - entry REQUEST 2015-08-10 12:40:40,148 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:40:40,148 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:784 - exit TRACE 2015-08-10 12:40:40,148 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:40:40,149 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:40:40,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:40:40,156 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:40:40,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:40:40,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:40:40,160 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:40:40,160 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:40:40,160 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:40:40,161 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:40:40,161 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:40,161 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:40,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:40,685 [Polling 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-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:40,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:40,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:43,685 [Polling 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-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:43,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:43,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:43,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:46,685 [Polling 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-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:46,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:46,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:46,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:46,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:46,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:47,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:40:47,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:40:47,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:40:47,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:40:47,257 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:49,685 [Polling 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-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:49,685 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:49,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:50,148 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:782 - entry REQUEST 2015-08-10 12:40:50,149 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:40:50,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:784 - exit TRACE 2015-08-10 12:40:50,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:40:50,150 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:50,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:50,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:40:50,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:40:50,156 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:40:50,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:40:50,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:40:50,161 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:50,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:52,686 [Polling 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-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:52,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:52,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:55,686 [Polling 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-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:55,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:55,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:56,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:40:56,260 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:40:56,260 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:40:56,261 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:40:56,261 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:40:58,686 [Polling 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-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:40:58,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:40:58,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:00,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:782 - entry REQUEST 2015-08-10 12:41:00,149 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:41:00,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:784 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:00,150 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:00,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:00,158 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:00,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:00,159 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:00,163 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:41:00,164 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:41:00,164 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:41:00,164 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:41:00,164 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:00,164 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:00,164 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:01,686 [Polling 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-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:01,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:01,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:04,686 [Polling 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-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:04,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:04,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:05,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:41:05,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:41:05,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:41:05,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:41:05,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:07,686 [Polling 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-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:07,686 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:07,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:782 - entry REQUEST 2015-08-10 12:41:10,150 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:41:10,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:784 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:10,151 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:10,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:10,157 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:10,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:10,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:41:10,162 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:10,162 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:10,687 [Polling 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-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:10,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:10,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:13,687 [Polling 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-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:13,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:13,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:14,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:41:14,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:41:14,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:41:14,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:41:14,259 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:16,687 [Polling 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-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:16,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:16,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:19,687 [Polling 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-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:19,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:19,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:20,149 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:782 - entry REQUEST 2015-08-10 12:41:20,150 [dserver/JDeviceForEvent/jdEvt1] - Operation ping requested from PC5-HP.ncra.tifr.res.in TRACE 2015-08-10 12:41:20,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.ping:784 - exit TRACE 2015-08-10 12:41:20,150 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:20,151 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:20,151 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:20,156 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1597 - entry with (ZmqEventSubscriptionChange) REQUEST 2015-08-10 12:41:20,157 [dserver/JDeviceForEvent/jdEvt1] - Operation command_inout_4 (cmd = ZmqEventSubscriptionChange) from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1697 - entry TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:90 - entry TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.device.StateImpl.updateState:117 - exit DEBUG 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1725 - execute command ZmqEventSubscriptionChange from DEVICE TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:87 - entry with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:57 - entry TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:690 - entry DEBUG 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:799 - Subscribe event for jd/evt/1/speed with type CHANGE_EVENT TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.admin.AdminDevice.zmqEventSubscriptionChange:805 - exit TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:354 - entry DEBUG 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.getConnectionParameters:375 - starting event tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change DEBUG 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.buildConnectionParameters:414 - event registered for tango://PC5-HP.ncra.tifr.res.in:20000/jd/evt/1/speed.idl5_change TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.s.command.ReflectCommandBehavior.execute:80 - exit TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.command.CommandImpl.execute:95 - exit with (ZmqEventSubscriptionChange) TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:20,157 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.commandHandler:1736 - exit TRACE 2015-08-10 12:41:20,158 [RequestProcessor-5 - dserver/JDeviceForEvent/jdEvt1] org.tango.server.servant.DeviceImpl.command_inout_4:1619 - exit TRACE 2015-08-10 12:41:20,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1111 - entry with ([speed]) REQUEST 2015-08-10 12:41:20,161 [JD/Evt/1] - read_attributes_5 [speed] from CACHE_DEV requested from PC5-HP.ncra.tifr.res.in (Java client with main class com.intellij.rt.execution.application.AppMain - PID=5516) DEBUG 2015-08-10 12:41:20,161 [RequestProcessor-5 - JD/Evt/1] org.tango.server.lock.ClientLocking.checkClientLocking:145 - check for client giop:tcp:192.168.118.210:51894 - Java client with Main class com.intellij.rt.execution.application.AppMain - PID=5516 DEBUG 2015-08-10 12:41:20,161 [RequestProcessor-5 - JD/Evt/1] o.t.s.servant.AttributeGetterSetter.getAttributesValues5:210 - read from CACHE Speed TRACE 2015-08-10 12:41:20,162 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:20,162 [RequestProcessor-5 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:20,162 [RequestProcessor-5 - JD/Evt/1] org.tango.server.servant.DeviceImpl.read_attributes_5:1149 - exit DEBUG 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:22,687 [Polling 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-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:22,687 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:22,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:23,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:592 - entry TRACE 2015-08-10 12:41:23,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:283 - entry TRACE 2015-08-10 12:41:23,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventImpl.marshall:289 - exit DEBUG 2015-08-10 12:41:23,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:603 - Heartbeat sent for tango://PC5-HP.ncra.tifr.res.in:20000/dserver/jdeviceforevent/jdevt1.heartbeat TRACE 2015-08-10 12:41:23,258 [Event HeartBeat - dserver/JDeviceForEvent/jdEvt1] org.tango.server.events.EventManager.run:606 - exit DEBUG 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:25,688 [Polling 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-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:25,688 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:25,689 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit DEBUG 2015-08-10 12:41:28,688 [Polling 1 - JD/Evt/1] o.tango.server.cache.CacheRefresher.run:51 - refresh attrTangoPollingCache.JD/Evt/1/Speed DEBUG 2015-08-10 12:41:28,688 [Polling 1 - JD/Evt/1] o.t.s.c.AttributeCacheEntryFactory.createEntry:68 - Creating entry for key = speed , attribute JD/Evt/1/Speed TRACE 2015-08-10 12:41:28,688 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:28,689 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=PRE_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:28,689 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:28,689 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit TRACE 2015-08-10 12:41:28,689 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:185 - entry with (Speed) DEBUG 2015-08-10 12:41:28,690 [Polling 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-10 12:41:28,690 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:196 - entry TRACE 2015-08-10 12:41:28,690 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.getSpeed:202 - exit TRACE 2015-08-10 12:41:28,690 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:199 - entry with (Speed) TRACE 2015-08-10 12:41:28,691 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:139 - entry TRACE 2015-08-10 12:41:28,692 [Polling 1 - JD/Evt/1] o.t.server.idl.TangoIDLAttributeUtil.toAttributeValue5:168 - exit TRACE 2015-08-10 12:41:28,692 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:251 - exit with (Speed) TRACE 2015-08-10 12:41:28,692 [Polling 1 - JD/Evt/1] o.t.server.attribute.AttributeImpl.updateValue:189 - exit with (Speed) TRACE 2015-08-10 12:41:28,692 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:445 - entry TRACE 2015-08-10 12:41:28,693 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:146 - entry TRACE 2015-08-10 12:41:28,693 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:99 - entry TRACE 2015-08-10 12:41:28,693 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:65 - entry DEBUG 2015-08-10 12:41:28,693 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:80 - QUALITY event must send: false TRACE 2015-08-10 12:41:28,693 [Polling 1 - JD/Evt/1] o.t.s.events.QualityEventTrigger.isSendEvent:81 - exit DEBUG 2015-08-10 12:41:28,694 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:154 - CHANGE event for Speed must send: false TRACE 2015-08-10 12:41:28,694 [Polling 1 - JD/Evt/1] o.t.server.events.ChangeEventTrigger.isSendEvent:155 - exit TRACE 2015-08-10 12:41:28,694 [Polling 1 - JD/Evt/1] org.tango.server.events.EventImpl.pushAttributeEvent:154 - exit TRACE 2015-08-10 12:41:28,694 [Polling 1 - JD/Evt/1] org.tango.server.events.EventManager.pushAttributeEvent:453 - exit TRACE 2015-08-10 12:41:28,694 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:74 - entry TRACE 2015-08-10 12:41:28,695 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:151 - entry with (InvocationContext[context=POST_READ_ATTRIBUTE,callType=POLLING,names=[Speed]]) TRACE 2015-08-10 12:41:28,695 [Polling 1 - JD/Evt/1] o.t.jdeviceforevent.JDeviceForEvent.aroundInvoke:157 - exit TRACE 2015-08-10 12:41:28,695 [Polling 1 - JD/Evt/1] o.t.server.device.AroundInvokeImpl.aroundInvoke:90 - exit