Announcement

**Isomorphic** · 14 Feb 2019, 15:40

We need the server log, not just the error, as well as basic information about what type of request this was - was it actually an upload, or something else?

From the stack trace it just looks as if the request is correct in some way.

**mabboud** · 15 Feb 2019, 06:43

here is a a log with FINEST logging level

Code:

15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'elem'
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.fetchByRequestKey] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (failure): 'elem' instance of datasources: 0ms
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.fetchByRequestKey] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Parsed XML from (in memory stream): 1ms
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Tried to create DataSource  of type 'elem' but null was returned
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.870 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Created DataSource null of type 'elem' in the pooling flow
15-Feb-2019 09:31:33.870 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestImage_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations'
15-Feb-2019 09:31:33.870 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'transaction'
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] DataSource 229 acquired SQLDriver instance 30199068 during initialization
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.fetchByRequestKey] DataSource 233 acquired SQLDriver instance 14306313 during initialization
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (failure): 'transaction' instance of datasources: 0ms
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/criteria as Object.criteria type: Object
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] DataSource 320 acquired SQLDriver instance 30238389 during initialization
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/criteria'
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Tried to create DataSource  of type 'transaction' but null was returned
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource null of type 'transaction' in the pooling flow
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/criteria/criteria/InvestigationKey as Object.InvestigationKey type: long
15-Feb-2019 09:31:33.871 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE RequestTarget.RequestKey = $criteria.RequestKey
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'Object'
15-Feb-2019 09:31:33.871 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestImage_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/criteria': 0ms
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/operationConfig as Object.operationConfig type: Object
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource 323 of type 'Object' and assigned it to thread https-jsse-nio-8443-exec-3
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource 323 of type 'Object' in the pooling flow
15-Feb-2019 09:31:33.872 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] 233: Executing SQL query on 'defaultDS': SELECT ProductReturn.ProductReturnKey, ProductReturn.RequestTargetKey, ProductReturn.ReturnSheetCount, ProductReturn.ReturnTime, RequestTarget.RequestKey FROM cfm.ProductReturn LEFT OUTER JOIN cfm.RequestTarget ON ProductReturn.RequestTargetKey = RequestTarget.RequestTargetKey WHERE RequestTarget.RequestKey = 13255
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 323 of type 'Object'
15-Feb-2019 09:31:33.871 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/operationConfig'
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path ''
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.dataSource: value is: Investigation
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.fetchByRequestKey] makeObject() created an unpooled Connection '17085948'
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.xsi: value is: http://www.w3.org/2000/10/XMLSchema-instance
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operationType: value is: fetch
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.fetchByRequestKey] Borrowed connection '17085948'
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/transactionNum as Object.transactionNum type: long
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.textMatchStyle: value is: exact
15-Feb-2019 09:31:33.872 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] Executing SQL query on 'defaultDS' using connection '17085948': SELECT ProductReturn.ProductReturnKey, ProductReturn.RequestTargetKey, ProductReturn.ReturnSheetCount, ProductReturn.ReturnTime, RequestTarget.RequestKey FROM cfm.ProductReturn LEFT OUTER JOIN cfm.RequestTarget ON ProductReturn.RequestTargetKey = RequestTarget.RequestTargetKey WHERE RequestTarget.RequestKey = 13255
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'List'
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/operationConfig': 1ms
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Eval'd row count query: SELECT COUNT(*) FROM cfm.RequestImages JOIN Images ON RequestImages.ImageKey = Images.ImageKey JOIN ImageCategories ON Images.ImageCategoryKey = ImageCategories.ImageCategoryKey WHERE (RequestImages.RequestKey=13255)
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.componentId: value is: departmentForm
15-Feb-2019 09:31:33.872 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (success): 'List' instance of datasources: 0ms
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] makeObject() created an unpooled Connection '25463364'
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.appID: value is: builtinApplication
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Borrowed connection '25463364'
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operation: value is: Investigation_fetch
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Using class com.isomorphic.datasource.ListType as implementer for DataSource List
15-Feb-2019 09:31:33.873 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestImage_fetch] Executing SQL query on 'defaultDS' using connection '25463364': SELECT COUNT(*) FROM cfm.RequestImages JOIN Images ON RequestImages.ImageKey = Images.ImageKey JOIN ImageCategories ON Images.ImageCategoryKey = ImageCategories.ImageCategoryKey WHERE (RequestImages.RequestKey=13255)
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/oldValues as Object.oldValues type: Object
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource 324 of type 'List' and assigned it to thread https-jsse-nio-8443-exec-3
15-Feb-2019 09:31:33.873 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/oldValues'
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource 324 of type 'List' in the pooling flow
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/oldValues/oldValues/InvestigationKey as Object.InvestigationKey type: long
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 324 of type 'List'
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/oldValues': 1ms
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] SQLTransform (1 rows): 0ms
15-Feb-2019 09:31:33.874 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations': 4ms
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations as Object.operations type: List
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to free up resources for request of type fetch on DataSource ProductReturn
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Counted 0 total rows in result set: 2ms
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'List' at path '/transaction/operations': 4ms
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'elem'
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Setting headers to disable caching
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.RequestImage_fetch] Query time: 2ms
15-Feb-2019 09:31:33.874 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '': 6ms
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Content type for RPC transaction: text/plain; charset=UTF-8
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (failure): 'elem' instance of datasources: 0ms
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug DataSource record created from XML with document element: 'transaction': 12ms
15-Feb-2019 09:31:33.875 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Getting output stream via servletResponse.getWriter()
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to free up resources for request of type fetch on DataSource RequestImage
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Tried to create DataSource  of type 'elem' but null was returned
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Processing 1 requests.
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Using charset: UTF-8 (CompressionFilter)
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Setting headers to disable caching
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Created DataSource null of type 'elem' in the pooling flow
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Content type for RPC transaction: text/plain; charset=UTF-8
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug non-DMI response, dropExtraFields: false
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Getting output stream via servletResponse.getWriter()
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'Investigation'
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations'
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 241 of type 'Investigation'
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Using charset: UTF-8 (CompressionFilter)
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/criteria as Object.criteria type: Object
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.ArrayList to JS Object: 0ms
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug non-DMI response, dropExtraFields: false
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/criteria'
15-Feb-2019 09:31:33.875 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 233
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Caching instance 241 of DS 'Investigation' from DSRequest.getDataSource()
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/criteria/criteria/InvestigationKey as Object.InvestigationKey type: long
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Freeing SQLDriver dbConnection 17085948 for SQLDriver instance 14306313
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Caching instance 241 of DS Investigation
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/criteria': 1ms
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to close $Proxy224 with hashcode "17085948"
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.ArrayList to JS Object: 0ms
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/operationConfig as Object.operationConfig type: Object
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/operationConfig'
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 229
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 301
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.dataSource: value is: InvestigationTarget
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Freeing SQLDriver dbConnection 25463364 for SQLDriver instance 30199068
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 301
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operationType: value is: fetch
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Request #1 (DSRequest) payload: {
    criteria:{
        InvestigationKey:13168
    },
    operationConfig:{
        dataSource:"Investigation",
        repo:null,
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    componentId:"departmentForm",
    appID:"builtinApplication",
    operation:"Investigation_fetch",
    oldValues:{
        InvestigationKey:13168
    }
}
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 301 of type 'RequestTarget'
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to close $Proxy224 with hashcode "25463364"
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.textMatchStyle: value is: exact
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 233
15-Feb-2019 09:31:33.876 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 320
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/operationConfig': 0ms
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 233
15-Feb-2019 09:31:33.876 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 320
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'UserGroup'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.appID: value is: builtinApplication
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 320 of type 'Images'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 233 of type 'ProductReturn'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 286 of type 'UserGroup'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operation: value is: InvestigationTarget_fetch
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/oldValues as Object.oldValues type: Object
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/oldValues'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Caching instance of toDS 'UserGroup' in the DSRequest map
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/IDACall (start->finish): 24ms
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 226
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Using upstream-specified mime type: text/plain; charset=UTF-8 (container-derived mime type was: null)
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/oldValues/oldValues/InvestigationKey as Object.InvestigationKey type: long
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource null, field null
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 226
15-Feb-2019 09:31:33.877 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug DataSource Investigation is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 226 of type 'ImageCategory'
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/oldValues': 0ms
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations': 2ms
15-Feb-2019 09:31:33.877 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 229
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource UserGroup, field UserGroupKey
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'List' at path '/transaction/operations': 4ms
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 229
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug DataSource UserGroup is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '': 6ms
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 229 of type 'RequestImage'
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug DataSource record created from XML with document element: 'transaction': 8ms
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/IDACall (start->finish): 31ms
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource UserGroup, field GroupName
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Using upstream-specified mime type: text/plain; charset=UTF-8 (container-derived mime type was: null)
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Processing 1 requests.
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug DataSource UserGroup is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.878 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'InvestigationTarget'
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 244 of type 'InvestigationTarget'
15-Feb-2019 09:31:33.878 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] No userTypes defined, allowing anyone access to all operations for this application
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Caching instance 244 of DS 'InvestigationTarget' from DSRequest.getDataSource()
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] No public zero-argument method named '_Investigation_fetch' found, performing generic datasource operation
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Caching instance 244 of DS InvestigationTarget
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Request #1 (DSRequest) payload: {
    criteria:{
        InvestigationKey:13168
    },
    operationConfig:{
        dataSource:"InvestigationTarget",
        repo:null,
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    appID:"builtinApplication",
    operation:"InvestigationTarget_fetch",
    oldValues:{
        InvestigationKey:13168
    }
}
15-Feb-2019 09:31:33.879 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.879 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] Performing fetch operation with
    criteria: {InvestigationKey:13168}    values: {InvestigationKey:13168}
15-Feb-2019 09:31:33.879 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'User'
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 283 of type 'User'
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Caching instance of toDS 'User' in the DSRequest map
15-Feb-2019 09:31:33.880 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource null, field null
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug DataSource InvestigationTarget is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource User, field UserKey
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug DataSource User is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.880 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] 241: Executing SQL query on 'defaultDS': SELECT Investigation.InvestigationKey, Investigation.RequestKey, Investigation.ShiftCalKey, Investigation.UserGroupKey, Investigation.ProductionDate, Investigation.Shift, UserGroups.GroupName FROM cfm.Investigation LEFT OUTER JOIN axm.UserGroups ON Investigation.UserGroupKey = UserGroups.UserGroupKey WHERE (Investigation.InvestigationKey=13168)
15-Feb-2019 09:31:33.880 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Processing security checks for DataSource User, field FullName
15-Feb-2019 09:31:33.881 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug DataSource User is not in the pre-checked list, processing...
15-Feb-2019 09:31:33.881 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] makeObject() created an unpooled Connection '14399471'
15-Feb-2019 09:31:33.881 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] Borrowed connection '14399471'
15-Feb-2019 09:31:33.881 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] No userTypes defined, allowing anyone access to all operations for this application
15-Feb-2019 09:31:33.881 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] Executing SQL query on 'defaultDS' using connection '14399471': SELECT Investigation.InvestigationKey, Investigation.RequestKey, Investigation.ShiftCalKey, Investigation.UserGroupKey, Investigation.ProductionDate, Investigation.Shift, UserGroups.GroupName FROM cfm.Investigation LEFT OUTER JOIN axm.UserGroups ON Investigation.UserGroupKey = UserGroups.UserGroupKey WHERE (Investigation.InvestigationKey=13168)
15-Feb-2019 09:31:33.881 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] No public zero-argument method named '_InvestigationTarget_fetch' found, performing generic datasource operation
15-Feb-2019 09:31:33.882 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] Time to convert java.util.LinkedHashMap to JS Object: 1ms
15-Feb-2019 09:31:33.882 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] Time to convert java.util.LinkedHashMap to JS Object: 0ms
15-Feb-2019 09:31:33.882 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] Performing fetch operation with
    criteria: {InvestigationKey:13168}    values: {InvestigationKey:13168}
15-Feb-2019 09:31:33.882 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.Investigation_fetch] SQLTransform (1 rows): 0ms
15-Feb-2019 09:31:33.882 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] DataSource 244 acquired SQLDriver instance 7426154 during initialization
15-Feb-2019 09:31:33.882 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 1 items
15-Feb-2019 09:31:33.882 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to free up resources for request of type fetch on DataSource Investigation
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Setting headers to disable caching
15-Feb-2019 09:31:33.883 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Content type for RPC transaction: text/plain; charset=UTF-8
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Getting output stream via servletResponse.getWriter()
15-Feb-2019 09:31:33.883 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] 244: Executing SQL query on 'defaultDS': SELECT InvestigationTarget.InvestigationTargetKey, InvestigationTarget.InvestigationKey, InvestigationTarget.UserKey, Users.FullName FROM cfm.InvestigationTarget LEFT OUTER JOIN Users ON InvestigationTarget.UserKey = Users.UserKey WHERE (InvestigationTarget.InvestigationKey=13168)
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Using charset: UTF-8 (CompressionFilter)
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] makeObject() created an unpooled Connection '18180363'
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug non-DMI response, dropExtraFields: false
15-Feb-2019 09:31:33.883 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug [builtinApplication.InvestigationTarget_fetch] Borrowed connection '18180363'
15-Feb-2019 09:31:33.883 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] Executing SQL query on 'defaultDS' using connection '18180363': SELECT InvestigationTarget.InvestigationTargetKey, InvestigationTarget.InvestigationKey, InvestigationTarget.UserKey, Users.FullName FROM cfm.InvestigationTarget LEFT OUTER JOIN Users ON InvestigationTarget.UserKey = Users.UserKey WHERE (InvestigationTarget.InvestigationKey=13168)
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.ArrayList to JS Object: 1ms
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 241
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Freeing SQLDriver dbConnection 14399471 for SQLDriver instance 11075661
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to close $Proxy224 with hashcode "14399471"
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 286
15-Feb-2019 09:31:33.884 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 286
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 286 of type 'UserGroup'
15-Feb-2019 09:31:33.885 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 241
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to free up resources for request of type fetch on DataSource InvestigationTarget
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 241
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 241 of type 'Investigation'
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Setting headers to disable caching
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/IDACall (start->finish): 25ms
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Using upstream-specified mime type: text/plain; charset=UTF-8 (container-derived mime type was: null)
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Content type for RPC transaction: text/plain; charset=UTF-8
15-Feb-2019 09:31:33.885 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 310 -> 245 bytes
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Getting output stream via servletResponse.getWriter()
15-Feb-2019 09:31:33.885 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Using charset: UTF-8 (CompressionFilter)
15-Feb-2019 09:31:33.886 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug non-DMI response, dropExtraFields: false
15-Feb-2019 09:31:33.886 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Time to convert java.util.ArrayList to JS Object: 0ms
15-Feb-2019 09:31:33.886 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 244
15-Feb-2019 09:31:33.886 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Freeing SQLDriver dbConnection 18180363 for SQLDriver instance 7426154
15-Feb-2019 09:31:33.886 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to close $Proxy224 with hashcode "18180363"
15-Feb-2019 09:31:33.887 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 283
15-Feb-2019 09:31:33.887 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 283
15-Feb-2019 09:31:33.887 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 283 of type 'User'
15-Feb-2019 09:31:33.887 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 244
15-Feb-2019 09:31:33.887 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug About to clear SQLDriver state for DS instance 244
15-Feb-2019 09:31:33.888 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Cleared and passivated DataSource 244 of type 'InvestigationTarget'
15-Feb-2019 09:31:33.888 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/IDACall (start->finish): 22ms
15-Feb-2019 09:31:33.888 FINE [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Using upstream-specified mime type: text/plain; charset=UTF-8 (container-derived mime type was: null)
15-Feb-2019 09:31:33.888 INFO [https-jsse-nio-8443-exec-3] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Info: '/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\ToolStrip\separator_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\ToolStrip\separator_Disabled.png'
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png'
Path Info: '/Enterprise/images/actions/add_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\add_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\add_Disabled.png'
15-Feb-2019 09:31:33.926 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
15-Feb-2019 09:31:33.926 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Info: '/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\ToolStrip\separator_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\ToolStrip\separator_Disabled.png'
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png'
Path Info: '/Enterprise/images/actions/add_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\add_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\add_Disabled.png'
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png: Mime type 'image/png' is not compressable - not compressing
15-Feb-2019 09:31:33.926 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png: Mime type 'image/png' is not compressable - not compressing
15-Feb-2019 09:31:33.927 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Info: '/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\ToolStrip\separator_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\ToolStrip\separator_Disabled.png'
15-Feb-2019 09:31:33.927 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png'
Path Info: '/Enterprise/images/actions/add_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\add_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\add_Disabled.png'
15-Feb-2019 09:31:33.927 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Info: '/Enterprise/images/ToolStrip/separator_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\ToolStrip\separator_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\ToolStrip\separator_Disabled.png'
15-Feb-2019 09:31:33.927 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png'
Path Info: '/Enterprise/images/actions/add_Disabled.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\add_Disabled.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\add_Disabled.png'
15-Feb-2019 09:31:33.927 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug request for file: C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\isomorphic\skins\Enterprise\images\ToolStrip\separator_Disabled.png
15-Feb-2019 09:31:33.928 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Expiration for static file: 86400 seconds from download
15-Feb-2019 09:31:33.928 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug request for file: C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\isomorphic\skins\Enterprise\images\actions\add_Disabled.png
15-Feb-2019 09:31:33.928 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Expiration for static file: 86400 seconds from download
15-Feb-2019 09:31:33.929 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png
15-Feb-2019 09:31:33.929 FINE [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/skins/Enterprise/images/ToolStrip/separator_Disabled.png (start->finish): 2ms
15-Feb-2019 09:31:33.929 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/actions/add_Disabled.png
15-Feb-2019 09:31:33.929 FINE [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/skins/Enterprise/images/actions/add_Disabled.png (start->finish): 2ms
15-Feb-2019 09:31:33.939 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/IDACall'
Request URI: '/avista/isomorphic/IDACall'
Path Info: 'null'
Path Translated: 'null'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\IDACall'
15-Feb-2019 09:31:33.940 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
15-Feb-2019 09:31:33.940 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/IDACall'
Request URI: '/avista/isomorphic/IDACall'
Path Info: 'null'
Path Translated: 'null'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\IDACall'
15-Feb-2019 09:31:33.940 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Request looks compressable.
15-Feb-2019 09:31:33.940 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/IDACall'
Request URI: '/avista/isomorphic/IDACall'
Path Info: 'null'
Path Translated: 'null'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\IDACall'
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: host:127.0.0.1:8443
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: connection:keep-alive
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: content-length:888
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: origin:https://127.0.0.1:8443
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: accept:*/*
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: referer:https://127.0.0.1:8443/avista/crm2/PendingCreditRequests.jsp
15-Feb-2019 09:31:33.941 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: accept-encoding:gzip, deflate, br
15-Feb-2019 09:31:33.942 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: accept-language:en-US,en;q=0.9,ar;q=0.8,es;q=0.7,fr;q=0.6
15-Feb-2019 09:31:33.942 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Header Name:Value pair: cookie:JSESSIONID=8173855B3057BDFCCE6C4D2047B16EBC; GLog=%7B%0A%20%20%20%20trackRPC%3Afalse%2C%20%0A%20%20%20%20isc_pageURL%3A%22https%3A//127.0.0.1%3A8443/examples/Timeline.html%22%2C%20%0A%20%20%20%20isc_pageGUID%3A%22FF28F930-66AC-478E-83D5-A8F9F9653BD0%22%2C%20%0A%20%20%20%20priorityDefaults%3A%7B%0A%20%20%20%20%20%20%20%20sgwtInternal%3A1%2C%20%0A%20%20%20%20%20%20%20%20redraws%3A4%2C%20%0A%20%20%20%20%20%20%20%20loadTime%3A4%2C%20%0A%20%20%20%20%20%20%20%20sizing%3A4%0A%20%20%20%20%7D%2C%20%0A%20%20%20%20defaultPriority%3A3%2C%20%0A%20%20%20%20left%3A96%2C%20%0A%20%20%20%20top%3A5%2C%20%0A%20%20%20%20width%3A1068%2C%20%0A%20%20%20%20height%3A818%0A%7D; isc_cState=ready; i18next=en-US
15-Feb-2019 09:31:33.942 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug session exists: 8173855B3057BDFCCE6C4D2047B16EBC
15-Feb-2019 09:31:33.942 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug remote user: admin
15-Feb-2019 09:31:33.942 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/IDACall'
Request URI: '/avista/isomorphic/IDACall'
Path Info: 'null'
Path Translated: 'null'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\IDACall'
15-Feb-2019 09:31:33.943 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/IDACall'
Request URI: '/avista/isomorphic/IDACall'
Path Info: 'null'
Path Translated: 'null'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\IDACall'
15-Feb-2019 09:31:33.945 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Parsed XML from (in memory stream): 2ms
15-Feb-2019 09:31:33.945 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'transaction'
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (failure): 'transaction' instance of datasources: 0ms
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Tried to create DataSource  of type 'transaction' but null was returned
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource null of type 'transaction' in the pooling flow
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'Object'
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource 325 of type 'Object' and assigned it to thread https-jsse-nio-8443-exec-4
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource 325 of type 'Object' in the pooling flow
15-Feb-2019 09:31:33.946 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 325 of type 'Object'
15-Feb-2019 09:31:33.947 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path ''
15-Feb-2019 09:31:33.947 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.xsi: value is: http://www.w3.org/2000/10/XMLSchema-instance
15-Feb-2019 09:31:33.947 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/transactionNum as Object.transactionNum type: long
15-Feb-2019 09:31:33.947 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'List'
15-Feb-2019 09:31:33.947 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (success): 'List' instance of datasources: 0ms
15-Feb-2019 09:31:33.948 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Using class com.isomorphic.datasource.ListType as implementer for DataSource List
15-Feb-2019 09:31:33.948 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource 326 of type 'List' and assigned it to thread https-jsse-nio-8443-exec-4
15-Feb-2019 09:31:33.948 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource 326 of type 'List' in the pooling flow
15-Feb-2019 09:31:33.949 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Activated DataSource 326 of type 'List'
15-Feb-2019 09:31:33.949 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations as Object.operations type: List
15-Feb-2019 09:31:33.949 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Borrowing object for 'elem'
15-Feb-2019 09:31:33.950 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug getInstanceFile (failure): 'elem' instance of datasources: 0ms
15-Feb-2019 09:31:33.950 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Tried to create DataSource  of type 'elem' but null was returned
15-Feb-2019 09:31:33.950 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Created DataSource null of type 'elem' in the pooling flow
15-Feb-2019 09:31:33.950 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations'
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/criteria as Object.criteria type: List
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'List' at path '/transaction/operations/elem/criteria': 0ms
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/operationConfig as Object.operationConfig type: Object
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating a 'Object' at path '/transaction/operations/elem/operationConfig'
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.dataSource: value is: RequestReasonGroup
15-Feb-2019 09:31:33.951 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operationType: value is: fetch
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.textMatchStyle: value is: exact
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations/elem/operationConfig': 1ms
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.appID: value is: builtinApplication
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Value provided for unknown field: Object.operation: value is: RequestReasonGroup_fetch
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Validating field:
/transaction/operations/elem/oldValues as Object.oldValues type: List
15-Feb-2019 09:31:33.952 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'List' at path '/transaction/operations/elem/oldValues': 0ms
15-Feb-2019 09:31:33.953 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '/transaction/operations': 3ms
15-Feb-2019 09:31:33.953 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'List' at path '/transaction/operations': 4ms
15-Feb-2019 09:31:33.953 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Done validating a 'Object' at path '': 6ms
15-Feb-2019 09:31:33.953 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug DataSource record created from XML with document element: 'transaction': 8ms
15-Feb-2019 09:31:33.953 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Processing 1 requests.
15-Feb-2019 09:31:33.954 SEVERE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.error com.isomorphic.servlet.IDACall top-level exception
 java.lang.NullPointerException
    at com.isomorphic.datasource.DSRequest.parseUploadedFiles(DSRequest.java:816)
    at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:705)
    at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2522)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:398)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:378)
    at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:147)
    at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:119)
    at com.isomorphic.servlet.IDACall.doPost(IDACall.java:79)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:661)
    at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:176)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:742)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.openejb.server.httpd.EEFilter.doFilter(EEFilter.java:65)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at com.isomorphic.servlet.CompressionFilter._doFilter(CompressionFilter.java:260)
    at com.isomorphic.servlet.BaseFilter.doFilter(BaseFilter.java:93)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at avista.ecl2.web.ISCCacheFilter.doFilter(ISCCacheFilter.java:36)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
    at org.apache.tomee.catalina.OpenEJBValve.invoke(OpenEJBValve.java:44)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:610)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81)
    at org.apache.tomee.catalina.OpenEJBSecurityListener$RequestCapturer.invoke(OpenEJBSecurityListener.java:97)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342)
    at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:800)
    at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
    at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:800)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1471)
    at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
    at java.lang.Thread.run(Thread.java:748)

15-Feb-2019 09:31:33.954 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Using charset: ISO-8859-1 (CompressionFilter)
15-Feb-2019 09:31:33.957 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/IDACall (start->finish): 16ms
15-Feb-2019 09:31:33.957 FINE [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/IDACall: Using upstream-specified mime type: text/html (container-derived mime type was: null)
15-Feb-2019 09:31:33.957 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 4776 -> 873 bytes
15-Feb-2019 09:31:34.077 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/remove.png'
Path Info: '/Enterprise/images/actions/remove.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\remove.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\remove.png'
15-Feb-2019 09:31:34.077 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/actions/remove.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
15-Feb-2019 09:31:34.077 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/remove.png'
Path Info: '/Enterprise/images/actions/remove.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\remove.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\remove.png'
15-Feb-2019 09:31:34.077 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/skins/Enterprise/images/actions/remove.png: Mime type 'image/png' is not compressable - not compressing
15-Feb-2019 09:31:34.078 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/remove.png'
Path Info: '/Enterprise/images/actions/remove.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\remove.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\remove.png'
15-Feb-2019 09:31:34.078 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/actions/remove.png'
Path Info: '/Enterprise/images/actions/remove.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\actions\remove.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\actions\remove.png'
15-Feb-2019 09:31:34.078 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug request for file: C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\isomorphic\skins\Enterprise\images\actions\remove.png
15-Feb-2019 09:31:34.079 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Expiration for static file: 86400 seconds from download
15-Feb-2019 09:31:34.080 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/actions/remove.png
15-Feb-2019 09:31:34.080 FINE [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/skins/Enterprise/images/actions/remove.png (start->finish): 2ms
15-Feb-2019 09:31:34.130 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/Dialog/warn.png'
Path Info: '/Enterprise/images/Dialog/warn.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\Dialog\warn.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\Dialog\warn.png'
15-Feb-2019 09:31:34.130 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/Dialog/warn.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
15-Feb-2019 09:31:34.131 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/Dialog/warn.png'
Path Info: '/Enterprise/images/Dialog/warn.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\Dialog\warn.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\Dialog\warn.png'
15-Feb-2019 09:31:34.131 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug /avista/isomorphic/skins/Enterprise/images/Dialog/warn.png: Mime type 'image/png' is not compressable - not compressing
15-Feb-2019 09:31:34.131 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/Dialog/warn.png'
Path Info: '/Enterprise/images/Dialog/warn.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\Dialog\warn.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\Dialog\warn.png'
15-Feb-2019 09:31:34.131 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Paths for request:
Servlet path: '/isomorphic/skins'
Request URI: '/avista/isomorphic/skins/Enterprise/images/Dialog/warn.png'
Path Info: '/Enterprise/images/Dialog/warn.png'
Path Translated: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\Enterprise\images\Dialog\warn.png'
Real FileSystem Path: 'C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\avista\isomorphic\skins\Enterprise\images\Dialog\warn.png'
15-Feb-2019 09:31:34.132 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug request for file: C:\apache-tomee-plus-7.0.5\apps\alliance.ear\avista-webapps\isomorphic\skins\Enterprise\images\Dialog\warn.png
15-Feb-2019 09:31:34.133 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Expiration for static file: 86400 seconds from download
15-Feb-2019 09:31:34.134 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/Dialog/warn.png
15-Feb-2019 09:31:34.134 FINE [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.debug Request: /avista/isomorphic/skins/Enterprise/images/Dialog/warn.png (start->finish): 3ms

**mabboud** · 15 Feb 2019, 07:06

i think i found the problem

Code:


        RequestSheet.fetchData({RequestKey:request.RequestKey},function(dsResponse,data,dsRequest){
                requestIdentificationForm.getItem('requestSheets').canvas.setData(data);
                if ( currentRequest.RequestStatusCode == 'APPROVAL')
                {
                        RequestReasonGroup.fetchData(data,function(dsResponse,data,dsRequest)
                        {
                            var approvalSigData = approvalForm.getItem('ApprovalSignature').canvas.getGrid().getData();                        
                            for (var i = 0; i < data.length; i++){
                                var found = false;
                                for(var s = 0 ; s < approvalSigData.length; s++)
                                {
                                    if ( approvalSigData[s].UserGroupKey == data[i].UserGroupKey)
                                    {
                                        approvalSigData[s]._canRemove=false;
                                        found = true;
                                        break;
                                    }
                                }
                                if (!found)
                                {
                                    data[i]._canRemove = false;
                                    data[i].SignatureTypeKey=getSignatureType('APP').SignatureTypeKey;
                                    data[i].UserKey = null;
                                    data[i].SigTime = null;
                                    approvalForm.getItem('ApprovalSignature').canvas.getGrid().startEditingNew(data[i]);
                                }
                            }
                            approvalForm.getItem('ApprovalSignature').canvas.markForRedraw();
                        }
                        );                    
                    }

            }
        );

the data returned from the first Request was empty or length = 0 which cause the second fetch to cause a null pointer not sure if this should be handleded on your end or not to prevent the NullPointerException

**Isomorphic** · 16 Feb 2019, 00:26

This enormous log is very difficult to read, please simply post logs with the default settings we ship (that�s why we ship those).

We can�t run your code so we can�t see what�s garbled in the second request. Posting the request as seen in the RPC tab of the Developer Console would solve this (along with a readable log).

**mabboud** · 19 Feb 2019, 06:29

Server Log

Code:

19-Feb-2019 09:25:27.909 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 283 -> 216 bytes
19-Feb-2019 09:25:42.216 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/headerIcons/close_Over.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:42.226 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/headerIcons/close_Over.png
19-Feb-2019 09:25:42.556 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:42.561 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:42.564 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] Performing fetch operation with
    criteria: {FeedbackID:"1902002"}    values: {FeedbackID:"1902002"}
19-Feb-2019 09:25:42.566 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:42.567 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] Executing SQL query on 'defaultDS' using connection '19504545': SELECT COUNT(*) FROM cfm.Request LEFT OUTER JOIN cfm.RequestStatus ON Request.CurrentStatusKey = RequestStatus.StatusKey LEFT OUTER JOIN cfm.RequestTarget ON Request.RequestKey = RequestTarget.RequestKey LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN cfm.RequestType ON Request.RequestTypeKey = RequestType.RequestTypeKey JOIN Users ON Request.CreateUserKey = Users.UserKey LEFT OUTER JOIN cfm.Investigation ON Request.RequestKey = Investigation.RequestKey WHERE (LOWER(Request.FeedbackID) LIKE '%1902002%' {ESCAPE '\'} AND Request.FeedbackID IS NOT NULL)
19-Feb-2019 09:25:42.568 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/loadingSmall.gif', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:42.571 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/loadingSmall.gif
19-Feb-2019 09:25:42.577 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] 76: Executing  query on 'defaultDS': SELECT * FROM (SELECT *, ROW_NUMBER() OVER (ORDER BY RequestKey) AS rowID FROM (SELECT TOP 100 PERCENT  Request.RequestKey, Request.FeedbackID, Request.CreateUserKey, Request.CreateTime, Request.RequestTypeKey, Request.ResolutionTypeKey, Request.ReceiveTime, Request.SCARName, Request.ReturnRequired, Request.ResolutionSheetCount, Request.DollarMSF, Request.DollarMisc, Request.DollarMiscDesc, Request.AccountingPeriodCode, Request.CurrentStatusKey, Request.ResolveTime, Request.SCARDate, Request.FeedbackComment, Request.ReturnAuthComment, Request.ProductReturnComment, Request.InvestigationComment, Request.ResolutionComment, Request.ApprovalComment, Request.RootCauseKey, Request.RootCauseComment, Request.CorrectiveActionKey, Request.CorrectiveActionComment, Request.DefectiveSheetCount, Request.RecoveryAmount, Request.RecoveryOrderNumber, Request.CreditComment, Request.CustomerInvoiceItemKey, Request.BillingCode, RequestStatus.LocalDesc AS RequestStatusDesc, CustomerOrder.OrderNumber, RequestTarget.OrderKey, CustomerOrder.CustomerPO, RequestTarget.InvoiceKey AS CustomerInvoiceKey, CustomerInvoices.InvoiceNumber, CustomerOrder.CustomerId, Customers.CustomerName, RequestType.LocalDesc AS RequestTypeDesc, RequestTarget.ShippingBOLKey, Users.FullName AS CreatedFullName, RequestStatus.ATSDesc AS RequestStatusCode, Investigation.InvestigationKey, Investigation.ProductionDate, Investigation.Shift, Investigation.ShiftCalKey, CustomerOrder.SheetLength, CustomerOrder.SheetWidth, CustomerOrder.Price FROM cfm.Request LEFT OUTER JOIN cfm.RequestStatus ON Request.CurrentStatusKey = RequestStatus.StatusKey LEFT OUTER JOIN cfm.RequestTarget ON Request.RequestKey = RequestTarget.RequestKey LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN cfm.RequestType ON Request.RequestTypeKey = RequestType.RequestTypeKey JOIN Users ON Request.CreateUserKey = Users.UserKey LEFT OUTER JOIN cfm.Investigation ON Request.RequestKey = Investigation.RequestKey WHERE (LOWER(Request.FeedbackID) LIKE '%1902002%' {ESCAPE '\'} AND Request.FeedbackID IS NOT NULL)) x) y WHERE y.rowID BETWEEN 1 AND 75
19-Feb-2019 09:25:42.587 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 1 items
19-Feb-2019 09:25:42.588 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 841 -> 557 bytes
19-Feb-2019 09:25:42.777 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/system/reference/skin/images/server_client_exchange.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:42.777 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/skins/Enterprise/images/DynamicForm/unchecked_Disabled.png', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:42.778 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info File C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/system/reference/skin/images/server_client_exchange.png not found, sending 404
19-Feb-2019 09:25:42.781 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info done streaming: C:/apache-tomee-plus-7.0.5/apps/alliance.ear/avista-webapps/isomorphic/skins/Enterprise/images/DynamicForm/unchecked_Disabled.png
19-Feb-2019 09:25:46.384 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.387 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.389 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.390 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.391 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] 76: Executing SQL query on 'defaultDS': SELECT Request.RequestKey, Request.FeedbackID, Request.CreateUserKey, Request.CreateTime, Request.RequestTypeKey, Request.ResolutionTypeKey, Request.ReceiveTime, Request.SCARName, Request.ReturnRequired, Request.ResolutionSheetCount, Request.DollarMSF, Request.DollarMisc, Request.DollarMiscDesc, Request.AccountingPeriodCode, Request.CurrentStatusKey, Request.ResolveTime, Request.SCARDate, Request.FeedbackComment, Request.ReturnAuthComment, Request.ProductReturnComment, Request.InvestigationComment, Request.ResolutionComment, Request.ApprovalComment, Request.RootCauseKey, Request.RootCauseComment, Request.CorrectiveActionKey, Request.CorrectiveActionComment, Request.DefectiveSheetCount, Request.RecoveryAmount, Request.RecoveryOrderNumber, Request.CreditComment, Request.CustomerInvoiceItemKey, Request.BillingCode, RequestStatus.LocalDesc AS RequestStatusDesc, CustomerOrder.OrderNumber, RequestTarget.OrderKey, CustomerOrder.CustomerPO, RequestTarget.InvoiceKey AS CustomerInvoiceKey, CustomerInvoices.InvoiceNumber, CustomerOrder.CustomerId, Customers.CustomerName, RequestType.LocalDesc AS RequestTypeDesc, RequestTarget.ShippingBOLKey, Users.FullName AS CreatedFullName, RequestStatus.ATSDesc AS RequestStatusCode, Investigation.InvestigationKey, Investigation.ProductionDate, Investigation.Shift, Investigation.ShiftCalKey, CustomerOrder.SheetLength, CustomerOrder.SheetWidth, CustomerOrder.Price FROM cfm.Request LEFT OUTER JOIN cfm.RequestStatus ON Request.CurrentStatusKey = RequestStatus.StatusKey LEFT OUTER JOIN cfm.RequestTarget ON Request.RequestKey = RequestTarget.RequestKey LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN cfm.RequestType ON Request.RequestTypeKey = RequestType.RequestTypeKey JOIN Users ON Request.CreateUserKey = Users.UserKey LEFT OUTER JOIN cfm.Investigation ON Request.RequestKey = Investigation.RequestKey WHERE (Request.RequestKey=13255)
19-Feb-2019 09:25:46.391 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Request_fetch] Executing SQL query on 'defaultDS' using connection '12713841': SELECT Request.RequestKey, Request.FeedbackID, Request.CreateUserKey, Request.CreateTime, Request.RequestTypeKey, Request.ResolutionTypeKey, Request.ReceiveTime, Request.SCARName, Request.ReturnRequired, Request.ResolutionSheetCount, Request.DollarMSF, Request.DollarMisc, Request.DollarMiscDesc, Request.AccountingPeriodCode, Request.CurrentStatusKey, Request.ResolveTime, Request.SCARDate, Request.FeedbackComment, Request.ReturnAuthComment, Request.ProductReturnComment, Request.InvestigationComment, Request.ResolutionComment, Request.ApprovalComment, Request.RootCauseKey, Request.RootCauseComment, Request.CorrectiveActionKey, Request.CorrectiveActionComment, Request.DefectiveSheetCount, Request.RecoveryAmount, Request.RecoveryOrderNumber, Request.CreditComment, Request.CustomerInvoiceItemKey, Request.BillingCode, RequestStatus.LocalDesc AS RequestStatusDesc, CustomerOrder.OrderNumber, RequestTarget.OrderKey, CustomerOrder.CustomerPO, RequestTarget.InvoiceKey AS CustomerInvoiceKey, CustomerInvoices.InvoiceNumber, CustomerOrder.CustomerId, Customers.CustomerName, RequestType.LocalDesc AS RequestTypeDesc, RequestTarget.ShippingBOLKey, Users.FullName AS CreatedFullName, RequestStatus.ATSDesc AS RequestStatusCode, Investigation.InvestigationKey, Investigation.ProductionDate, Investigation.Shift, Investigation.ShiftCalKey, CustomerOrder.SheetLength, CustomerOrder.SheetWidth, CustomerOrder.Price FROM cfm.Request LEFT OUTER JOIN cfm.RequestStatus ON Request.CurrentStatusKey = RequestStatus.StatusKey LEFT OUTER JOIN cfm.RequestTarget ON Request.RequestKey = RequestTarget.RequestKey LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN cfm.RequestType ON Request.RequestTypeKey = RequestType.RequestTypeKey JOIN Users ON Request.CreateUserKey = Users.UserKey LEFT OUTER JOIN cfm.Investigation ON Request.RequestKey = Investigation.RequestKey WHERE (Request.RequestKey=13255)
19-Feb-2019 09:25:46.393 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 1 items
19-Feb-2019 09:25:46.394 INFO [https-jsse-nio-8443-exec-6] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 833 -> 550 bytes
19-Feb-2019 09:25:46.451 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.454 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.455 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestTarget_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.455 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestTarget_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.456 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestTarget_fetch] 305: Executing SQL query on 'defaultDS': SELECT RequestTarget.RequestTargetKey, RequestTarget.RequestKey, RequestTarget.OrderKey, RequestTarget.ShippingBOLKey, RequestTarget.InvoiceKey, CustomerOrder.OrderNumber, CustomerOrder.CustomerPO, Customers.CustomerName, CustomerOrder.CustomerId, CustomerInvoices.InvoiceNumber, CustomerOrder.SheetLength, CustomerOrder.SheetWidth, CustomerOrder.Price FROM cfm.RequestTarget LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey WHERE (RequestTarget.RequestKey=13255)
19-Feb-2019 09:25:46.456 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestTarget_fetch] Executing SQL query on 'defaultDS' using connection '24001793': SELECT RequestTarget.RequestTargetKey, RequestTarget.RequestKey, RequestTarget.OrderKey, RequestTarget.ShippingBOLKey, RequestTarget.InvoiceKey, CustomerOrder.OrderNumber, CustomerOrder.CustomerPO, Customers.CustomerName, CustomerOrder.CustomerId, CustomerInvoices.InvoiceNumber, CustomerOrder.SheetLength, CustomerOrder.SheetWidth, CustomerOrder.Price FROM cfm.RequestTarget LEFT OUTER JOIN CustomerOrder ON RequestTarget.OrderKey = CustomerOrder.OrderKey LEFT OUTER JOIN Customers ON CustomerOrder.CustomerId = Customers.CustomerId LEFT OUTER JOIN CustomerInvoices ON RequestTarget.InvoiceKey = CustomerInvoices.CustomerInvoiceKey WHERE (RequestTarget.RequestKey=13255)
19-Feb-2019 09:25:46.458 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
19-Feb-2019 09:25:46.459 INFO [https-jsse-nio-8443-exec-8] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
19-Feb-2019 09:25:46.463 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.466 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.467 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Signature_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.467 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Signature_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.468 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Signature_fetch] 190: Executing SQL query on 'defaultDS': SELECT Signature.SignatureKey, Signature.RequestKey, Signature.SignatureTypeKey, Signature.UserGroupKey, Signature.UserKey, Signature.SigTime, SignatureType.LocalID AS SigTypeLocalID, UserGroups.GroupName FROM cfm.Signature LEFT OUTER JOIN cfm.SignatureType ON Signature.SignatureTypeKey = SignatureType.SignatureTypeKey LEFT OUTER JOIN axm.UserGroups ON Signature.UserGroupKey = UserGroups.UserGroupKey WHERE (Signature.RequestKey=13255)
19-Feb-2019 09:25:46.469 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Signature_fetch] Executing SQL query on 'defaultDS' using connection '11370588': SELECT Signature.SignatureKey, Signature.RequestKey, Signature.SignatureTypeKey, Signature.UserGroupKey, Signature.UserKey, Signature.SigTime, SignatureType.LocalID AS SigTypeLocalID, UserGroups.GroupName FROM cfm.Signature LEFT OUTER JOIN cfm.SignatureType ON Signature.SignatureTypeKey = SignatureType.SignatureTypeKey LEFT OUTER JOIN axm.UserGroups ON Signature.UserGroupKey = UserGroups.UserGroupKey WHERE (Signature.RequestKey=13255)
19-Feb-2019 09:25:46.470 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 1 items
19-Feb-2019 09:25:46.471 INFO [https-jsse-nio-8443-exec-5] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 310 -> 241 bytes
19-Feb-2019 09:25:46.479 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.482 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.483 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestUnit_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.484 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestUnit_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.484 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.484 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestUnit_fetch] 208: Executing SQL query on 'defaultDS': SELECT RequestUnit.RequestUnitKey, RequestUnit.RequestKey, RequestUnit.UnitKey FROM cfm.RequestUnit WHERE (RequestUnit.RequestKey=13255)
19-Feb-2019 09:25:46.484 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestUnit_fetch] Executing SQL query on 'defaultDS' using connection '2288630': SELECT RequestUnit.RequestUnitKey, RequestUnit.RequestKey, RequestUnit.UnitKey FROM cfm.RequestUnit WHERE (RequestUnit.RequestKey=13255)
19-Feb-2019 09:25:46.485 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
19-Feb-2019 09:25:46.486 INFO [https-jsse-nio-8443-exec-1] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
19-Feb-2019 09:25:46.487 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.488 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestSheet_fetch] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.489 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestSheet_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.489 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestSheet_fetch] 210: Executing SQL query on 'defaultDS': SELECT RequestSheet.RequestSheetKey, RequestSheet.RequestKey, RequestSheet.ReasonKey, RequestSheet.SheetCount FROM cfm.RequestSheet WHERE (RequestSheet.RequestKey=13255)
19-Feb-2019 09:25:46.490 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info [builtinApplication.RequestSheet_fetch] Executing SQL query on 'defaultDS' using connection '30030664': SELECT RequestSheet.RequestSheetKey, RequestSheet.RequestKey, RequestSheet.ReasonKey, RequestSheet.SheetCount FROM cfm.RequestSheet WHERE (RequestSheet.RequestKey=13255)
19-Feb-2019 09:25:46.490 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.491 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
19-Feb-2019 09:25:46.492 INFO [https-jsse-nio-8443-exec-4] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
19-Feb-2019 09:25:46.494 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.495 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] Performing fetch operation with
    criteria: {RequestKey:13255}    values: {RequestKey:13255}
19-Feb-2019 09:25:46.496 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE RequestTarget.RequestKey = $criteria.RequestKey
19-Feb-2019 09:25:46.496 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] 233: Executing SQL query on 'defaultDS': SELECT ProductReturn.ProductReturnKey, ProductReturn.RequestTargetKey, ProductReturn.ReturnSheetCount, ProductReturn.ReturnTime, RequestTarget.RequestKey FROM cfm.ProductReturn LEFT OUTER JOIN cfm.RequestTarget ON ProductReturn.RequestTargetKey = RequestTarget.RequestTargetKey WHERE RequestTarget.RequestKey = 13255
19-Feb-2019 09:25:46.497 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info [builtinApplication.fetchByRequestKey] Executing SQL query on 'defaultDS' using connection '33003625': SELECT ProductReturn.ProductReturnKey, ProductReturn.RequestTargetKey, ProductReturn.ReturnSheetCount, ProductReturn.ReturnTime, RequestTarget.RequestKey FROM cfm.ProductReturn LEFT OUTER JOIN cfm.RequestTarget ON ProductReturn.RequestTargetKey = RequestTarget.RequestTargetKey WHERE RequestTarget.RequestKey = 13255
19-Feb-2019 09:25:46.497 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.499 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
19-Feb-2019 09:25:46.545 INFO [https-jsse-nio-8443-exec-7] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
19-Feb-2019 09:25:46.546 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.546 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.548 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] Performing fetch operation with
    criteria: {InvestigationKey:13168}    values: {InvestigationKey:13168}
19-Feb-2019 09:25:46.549 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.549 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] 241: Executing SQL query on 'defaultDS': SELECT Investigation.InvestigationKey, Investigation.RequestKey, Investigation.ShiftCalKey, Investigation.UserGroupKey, Investigation.ProductionDate, Investigation.Shift, UserGroups.GroupName FROM cfm.Investigation LEFT OUTER JOIN axm.UserGroups ON Investigation.UserGroupKey = UserGroups.UserGroupKey WHERE (Investigation.InvestigationKey=13168)
19-Feb-2019 09:25:46.550 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info [builtinApplication.Investigation_fetch] Executing SQL query on 'defaultDS' using connection '11765279': SELECT Investigation.InvestigationKey, Investigation.RequestKey, Investigation.ShiftCalKey, Investigation.UserGroupKey, Investigation.ProductionDate, Investigation.Shift, UserGroups.GroupName FROM cfm.Investigation LEFT OUTER JOIN axm.UserGroups ON Investigation.UserGroupKey = UserGroups.UserGroupKey WHERE (Investigation.InvestigationKey=13168)
19-Feb-2019 09:25:46.550 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info Performing 1 operation(s)
19-Feb-2019 09:25:46.550 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] Performing fetch operation with
    criteria: {InvestigationKey:13168}    values: {InvestigationKey:13168}
19-Feb-2019 09:25:46.551 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 1 items
19-Feb-2019 09:25:46.551 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause$defaultAnsiJoinClause WHERE $defaultWhereClause
19-Feb-2019 09:25:46.551 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] 244: Executing SQL query on 'defaultDS': SELECT InvestigationTarget.InvestigationTargetKey, InvestigationTarget.InvestigationKey, InvestigationTarget.UserKey, Users.FullName FROM cfm.InvestigationTarget LEFT OUTER JOIN Users ON InvestigationTarget.UserKey = Users.UserKey WHERE (InvestigationTarget.InvestigationKey=13168)
19-Feb-2019 09:25:46.552 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info [builtinApplication.InvestigationTarget_fetch] Executing SQL query on 'defaultDS' using connection '18175031': SELECT InvestigationTarget.InvestigationTargetKey, InvestigationTarget.InvestigationKey, InvestigationTarget.UserKey, Users.FullName FROM cfm.InvestigationTarget LEFT OUTER JOIN Users ON InvestigationTarget.UserKey = Users.UserKey WHERE (InvestigationTarget.InvestigationKey=13168)
19-Feb-2019 09:25:46.552 INFO [https-jsse-nio-8443-exec-9] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 310 -> 245 bytes
19-Feb-2019 09:25:46.553 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info DSResponse: List with 0 items
19-Feb-2019 09:25:46.554 INFO [https-jsse-nio-8443-exec-2] com.isomorphic.log.Slf4jLogger.info /avista/isomorphic/IDACall: 188 -> 152 bytes
19-Feb-2019 09:25:46.564 INFO [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.info URL: '/avista/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.109 Safari/537.36': Safari with Accept-Encoding header
19-Feb-2019 09:25:46.568 SEVERE [https-jsse-nio-8443-exec-10] com.isomorphic.log.Slf4jLogger.error com.isomorphic.servlet.IDACall top-level exception
 java.lang.NullPointerException
    at com.isomorphic.datasource.DSRequest.parseUploadedFiles(DSRequest.java:816)
    at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:705)
    at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2522)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:398)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:378)
    at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:147)
    at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:119)
    at com.isomorphic.servlet.IDACall.doPost(IDACall.java:79)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:661)
    at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:176)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:742)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.openejb.server.httpd.EEFilter.doFilter(EEFilter.java:65)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at com.isomorphic.servlet.CompressionFilter._doFilter(CompressionFilter.java:260)
    at com.isomorphic.servlet.BaseFilter.doFilter(BaseFilter.java:93)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at avista.ecl2.web.ISCCacheFilter.doFilter(ISCCacheFilter.java:36)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
    at org.apache.tomee.catalina.OpenEJBValve.invoke(OpenEJBValve.java:44)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:610)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81)
    at org.apache.tomee.catalina.OpenEJBSecurityListener$RequestCapturer.invoke(OpenEJBSecurityListener.java:97)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342)
    at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:800)
    at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
    at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:800)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1471)
    at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
    at java.lang.Thread.run(Thread.java:748)

RPCRequest :

Code:

{
    "actionURL":"https://127.0.0.1:8443/avista/isomorphic/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":[
        ], 
        "operationConfig":{
            "dataSource":"RequestReasonGroup", 
            "repo":null, 
            "operationType":"fetch", 
            "textMatchStyle":"exact"
        }, 
        "appID":"builtinApplication", 
        "operation":"RequestReasonGroup_fetch", 
        "oldValues":[
        ]
    }
}

DSRequest :

Code:

{
    dataSource:"RequestReasonGroup", 
    operationType:"fetch", 
    data:[
    ], 
    textMatchStyle:"exact", 
    showPrompt:true, 
    oldValues:[
    ], 
    requestId:"RequestReasonGroup$62757", 
    fallbackToEval:false, 
    lastClientEventThreadCode:"XRP6", 
    bypassCache:true, 
    dataProtocol:"getParams"
}

**Isomorphic** · 19 Feb 2019, 13:28

See the RPC tab: you are sending a request where criteria is an empty array, that�s invalid. It should be either not specified or an empty JS Object (which becomes a Map server side).

**mabboud** · 20 Feb 2019, 06:59

ok thank i'll check for an empty array

Announcement

NullPointerException IDACall top-level exception

Comment

Comment

Comment

Comment

Comment

Comment

Comment