=== 2013-12-02 12:29:57,700 [04-8] DEBUG RPCDMI - rpc returned data === 2013-12-02 12:29:57,700 [04-8] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2013-12-02 12:29:57,700 [04-3] WARN RPCManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] getDSRequest() on multiop RPC (8 requests pending) java.lang.Exception at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:460) at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:448) at qa.gov.moi.erp.web.dmi.CustodyIssueValidatorDMI.condition(CustodyIssueValidatorDMI.java:43) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) at java.lang.reflect.Method.invoke(Method.java:599) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:973) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:805) at com.isomorphic.util.DefaultValidators$serverCustom.callServerObject(DefaultValidators.java:1351) at com.isomorphic.util.DefaultValidators$serverCustom.validate(DefaultValidators.java:1269) at com.isomorphic.util.DefaultValidators.processValidator(DefaultValidators.java:271) at com.isomorphic.util.DefaultValidators.validateField(DefaultValidators.java:237) at com.isomorphic.datasource.SimpleType.validateValue(SimpleType.java:97) at com.isomorphic.datasource.SimpleType.create(SimpleType.java:73) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1619) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1566) at com.isomorphic.datasource.BasicDataSource.toRecord(BasicDataSource.java:1154) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1091) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1052) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2585) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2534) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1440) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723) at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658) at com.isomorphic.application.AppBase.execute(AppBase.java:491) at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2431) at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215) at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172) at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137) at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:637) at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:859) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:602) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489) at java.lang.Thread.run(Thread.java:735) === 2013-12-02 12:29:57,700 [04-5] WARN RPCManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] getDSRequest() on multiop RPC (8 requests pending) java.lang.Exception at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:460) at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:448) at qa.gov.moi.erp.web.dmi.CustodyIssueValidatorDMI.condition(CustodyIssueValidatorDMI.java:43) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) at java.lang.reflect.Method.invoke(Method.java:599) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:973) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:805) at com.isomorphic.util.DefaultValidators$serverCustom.callServerObject(DefaultValidators.java:1351) at com.isomorphic.util.DefaultValidators$serverCustom.validate(DefaultValidators.java:1269) at com.isomorphic.util.DefaultValidators.processValidator(DefaultValidators.java:271) at com.isomorphic.util.DefaultValidators.validateField(DefaultValidators.java:237) at com.isomorphic.datasource.SimpleType.validateValue(SimpleType.java:97) at com.isomorphic.datasource.SimpleType.create(SimpleType.java:73) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1619) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1566) at com.isomorphic.datasource.BasicDataSource.toRecord(BasicDataSource.java:1154) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1091) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1052) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2585) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2534) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1440) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723) at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658) at com.isomorphic.application.AppBase.execute(AppBase.java:491) at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2431) at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215) at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172) at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137) at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:637) at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:859) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:602) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489) at java.lang.Thread.run(Thread.java:735) === 2013-12-02 12:29:57,700 [04-3] WARN RPCManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] getDSRequest() on multiop RPC (8 requests pending) java.lang.Exception at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:460) at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:448) at qa.gov.moi.erp.web.dmi.CustodyIssueValidatorDMI.condition(CustodyIssueValidatorDMI.java:43) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) at java.lang.reflect.Method.invoke(Method.java:599) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:973) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:805) at com.isomorphic.util.DefaultValidators$serverCustom.callServerObject(DefaultValidators.java:1351) at com.isomorphic.util.DefaultValidators$serverCustom.validate(DefaultValidators.java:1269) at com.isomorphic.util.DefaultValidators.processValidator(DefaultValidators.java:271) at com.isomorphic.util.DefaultValidators.validateField(DefaultValidators.java:237) at com.isomorphic.datasource.SimpleType.validateValue(SimpleType.java:97) at com.isomorphic.datasource.SimpleType.create(SimpleType.java:73) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1619) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1566) at com.isomorphic.datasource.BasicDataSource.toRecord(BasicDataSource.java:1154) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1091) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1052) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2585) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2534) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1440) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723) at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658) at com.isomorphic.application.AppBase.execute(AppBase.java:491) at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2431) at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215) at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172) at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137) at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:637) at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:859) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:602) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489) at java.lang.Thread.run(Thread.java:735) === 2013-12-02 12:29:57,700 [04-5] WARN RPCManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] getDSRequest() on multiop RPC (8 requests pending) java.lang.Exception at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:460) at com.isomorphic.rpc.RPCManager.getDSRequest(RPCManager.java:448) at qa.gov.moi.erp.web.dmi.CustodyIssueValidatorDMI.condition(CustodyIssueValidatorDMI.java:43) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) at java.lang.reflect.Method.invoke(Method.java:599) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:973) at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:805) at com.isomorphic.util.DefaultValidators$serverCustom.callServerObject(DefaultValidators.java:1351) at com.isomorphic.util.DefaultValidators$serverCustom.validate(DefaultValidators.java:1269) at com.isomorphic.util.DefaultValidators.processValidator(DefaultValidators.java:271) at com.isomorphic.util.DefaultValidators.validateField(DefaultValidators.java:237) at com.isomorphic.datasource.SimpleType.validateValue(SimpleType.java:97) at com.isomorphic.datasource.SimpleType.create(SimpleType.java:73) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1619) at com.isomorphic.datasource.BasicDataSource.validateFieldValue(BasicDataSource.java:1566) at com.isomorphic.datasource.BasicDataSource.toRecord(BasicDataSource.java:1154) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1091) at com.isomorphic.datasource.BasicDataSource.toRecords(BasicDataSource.java:1052) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2585) at com.isomorphic.datasource.DataSource.validateDSRequest(DataSource.java:2534) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1440) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723) at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658) at com.isomorphic.application.AppBase.execute(AppBase.java:491) at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2431) at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215) at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172) at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137) at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:637) at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:859) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:602) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489) at java.lang.Thread.run(Thread.java:735) === 2013-12-02 12:29:57,700 [04-8] INFO Compression - /ERP/isomorphic/IDACall: 2128 -> 844 bytes === 2013-12-02 12:29:57,731 [04-3] DEBUG XML - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] Parsed XML from D:\WorkSpace\SVN\29Oct2013\.metadata\.plugins\org.eclipse.wst.server.core\tmp1\wtpwebapps\ERPWeb\common\smartclientConfig\ds\ProdTree.ds.xml: 0ms === 2013-12-02 12:29:57,731 [04-3] DEBUG DeclarativeSecurity - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] Processing security checks for DataSource null, field null === 2013-12-02 12:29:57,731 [04-3] DEBUG AppBase - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2013-12-02 12:29:57,731 [04-3] DEBUG AppBase - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2013-12-02 12:29:57,731 [04-3] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Performing fetch operation with criteria: {PR0_NUMBER:"037/000007 "} values: {PR0_NUMBER:"037/000007 "} === 2013-12-02 12:29:57,731 [04-5] DEBUG DeclarativeSecurity - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] Processing security checks for DataSource null, field null === 2013-12-02 12:29:57,731 [04-5] DEBUG AppBase - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application === 2013-12-02 12:29:57,731 [04-3] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2013-12-02 12:29:57,731 [04-5] DEBUG AppBase - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation === 2013-12-02 12:29:57,731 [04-5] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Performing fetch operation with criteria: {PR0_NUMBER:"037/000007 "} values: {PR0_NUMBER:"037/000007 "} === 2013-12-02 12:29:57,731 [04-3] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] 203: Executing SQL query on 'EAD': SELECT VPR0_PRODUCT.PR0_BUY_DEPARTMENT, VPR0_PRODUCT.PR0_ISACTIVE, VPR0_PRODUCT.PR0_IS_CONSUMABLE, VPR0_PRODUCT.PR0_IS_SERIAL_DEFINED, VPR0_PRODUCT.PR0_NUMBER, VPR0_PRODUCT.PR0_PRODUCT_ID, VPR0_PRODUCT.PR0_PRODUCT_TYPE, VPR0_PRODUCT.PR0_REQ_DEPARTMENT FROM VPR0_PRODUCT WHERE (VPR0_PRODUCT.PR0_NUMBER='037/000007 ') === 2013-12-02 12:29:57,731 [04-5] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2013-12-02 12:29:57,731 [04-3] DEBUG SQLConnectionManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Borrowed connection '285872394' === 2013-12-02 12:29:57,731 [04-3] DEBUG SQLDriver - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] About to execute SQL query in 'EAD' using connection '285872394' === 2013-12-02 12:29:57,731 [04-3] INFO SQLDriver - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Executing SQL query on 'EAD': SELECT VPR0_PRODUCT.PR0_BUY_DEPARTMENT, VPR0_PRODUCT.PR0_ISACTIVE, VPR0_PRODUCT.PR0_IS_CONSUMABLE, VPR0_PRODUCT.PR0_IS_SERIAL_DEFINED, VPR0_PRODUCT.PR0_NUMBER, VPR0_PRODUCT.PR0_PRODUCT_ID, VPR0_PRODUCT.PR0_PRODUCT_TYPE, VPR0_PRODUCT.PR0_REQ_DEPARTMENT FROM VPR0_PRODUCT WHERE (VPR0_PRODUCT.PR0_NUMBER='037/000007 ') === 2013-12-02 12:29:57,731 [04-5] INFO SQLDataSource - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] 204: Executing SQL query on 'EAD': SELECT VPR0_PRODUCT.PR0_BUY_DEPARTMENT, VPR0_PRODUCT.PR0_ISACTIVE, VPR0_PRODUCT.PR0_IS_CONSUMABLE, VPR0_PRODUCT.PR0_IS_SERIAL_DEFINED, VPR0_PRODUCT.PR0_NUMBER, VPR0_PRODUCT.PR0_PRODUCT_ID, VPR0_PRODUCT.PR0_PRODUCT_TYPE, VPR0_PRODUCT.PR0_REQ_DEPARTMENT FROM VPR0_PRODUCT WHERE (VPR0_PRODUCT.PR0_NUMBER='037/000007 ') === 2013-12-02 12:29:57,731 [04-5] DEBUG SQLConnectionManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Borrowed connection '491265352' === 2013-12-02 12:29:57,731 [04-5] DEBUG SQLDriver - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] About to execute SQL query in 'EAD' using connection '491265352' === 2013-12-02 12:29:57,731 [04-5] INFO SQLDriver - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] Executing SQL query on 'EAD': SELECT VPR0_PRODUCT.PR0_BUY_DEPARTMENT, VPR0_PRODUCT.PR0_ISACTIVE, VPR0_PRODUCT.PR0_IS_CONSUMABLE, VPR0_PRODUCT.PR0_IS_SERIAL_DEFINED, VPR0_PRODUCT.PR0_NUMBER, VPR0_PRODUCT.PR0_PRODUCT_ID, VPR0_PRODUCT.PR0_PRODUCT_TYPE, VPR0_PRODUCT.PR0_REQ_DEPARTMENT FROM VPR0_PRODUCT WHERE (VPR0_PRODUCT.PR0_NUMBER='037/000007 ') === 2013-12-02 12:29:57,731 [04-3] DEBUG SQLTransform - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] isBeforeFirst()/isAfterLast() throwing exceptions - attempting workaround === 2013-12-02 12:29:57,731 [04-3] INFO DSResponse - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate, builtinApplication.null] DSResponse: List with 1 items === 2013-12-02 12:29:57,731 [04-3] DEBUG SQLDriver - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] Freeing SQLDriver dbConnection 285872394 === 2013-12-02 12:29:57,731 [04-3] DEBUG SQLConnectionManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] About to close PoolableConnection with hashcode "285872394" === 2013-12-02 12:29:57,731 [04-3] WARN RPCManager - [builtinApplication.TII1_CUSTODY_ISSUE_ITEM_DETAIL_validate] getDSRequest() on multiop RPC (8 requests pending)