=== 2016-01-04 14:11:56,709 [5-23] INFO RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; CTL_11; rv:11.0) like Gecko': Unsupported with Accept-Encoding header === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Cookie:GLog=%7B%0D%20%20%20%20trackRPC%3Atrue%2C%20%0D%20%20%20%20isc_pageURL%3A%22http%3A//127.0.0.1%3A8888/BuiltInDS.html%3Fgwt.codesvr%3D127.0.0.1%3A9997%22%2C%20%0D%20%20%20%20isc_pageGUID%3A%22F1E219C6-7D76-49E9-BAE0-5703862499D4%22%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20sgwtInternal%3A1%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20left%3A-1028%2C%20%0D%20%20%20%20top%3A-68%2C%20%0D%20%20%20%20width%3A1024%2C%20%0D%20%20%20%20height%3A717%0D%7D; isc_cState=ready; JSESSIONID=wfva0q949pj6yaqu7t7776dr === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888 === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Accept:*/* === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Content-Length:6690 === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8 === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; CTL_11; rv:11.0) like Gecko === 2016-01-04 14:11:56,709 [5-23] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/BuiltInDS.html?gwt.codesvr=127.0.0.1:9997 === 2016-01-04 14:11:56,710 [5-23] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache === 2016-01-04 14:11:56,710 [5-23] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate === 2016-01-04 14:11:56,710 [5-23] DEBUG IDACall - session exists: wfva0q949pj6yaqu7t7776dr === 2016-01-04 14:11:56,710 [5-23] DEBUG IDACall - remote user: null === 2016-01-04 14:11:56,733 [5-23] DEBUG XML - Parsed XML from (in memory stream): 8ms === 2016-01-04 14:11:56,733 [5-23] DEBUG ISCKeyedObjectPool - Borrowing object for 'transaction' === 2016-01-04 14:11:56,733 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'transaction' in the pooling flow === 2016-01-04 14:11:56,733 [5-23] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object' === 2016-01-04 14:11:56,734 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource 141 of type 'Object' and assigned it to thread qtp1600835385-23 === 2016-01-04 14:11:56,734 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource 141 of type 'Object' in the pooling flow === 2016-01-04 14:11:56,734 [5-23] DEBUG PoolableDataSourceFactory - Activated DataSource 141 of type 'Object' === 2016-01-04 14:11:56,734 [5-23] DEBUG ISCKeyedObjectPool - Borrowing object for 'List' === 2016-01-04 14:11:56,736 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource 142 of type 'List' and assigned it to thread qtp1600835385-23 === 2016-01-04 14:11:56,736 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource 142 of type 'List' in the pooling flow === 2016-01-04 14:11:56,736 [5-23] DEBUG PoolableDataSourceFactory - Activated DataSource 142 of type 'List' === 2016-01-04 14:11:56,737 [5-23] DEBUG ISCKeyedObjectPool - Borrowing object for 'elem' === 2016-01-04 14:11:56,737 [5-23] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'elem' in the pooling flow === 2016-01-04 14:11:56,742 [5-23] DEBUG RPCManager - Processing 1 requests. === 2016-01-04 14:11:56,742 [5-23] DEBUG ISCKeyedObjectPool - Borrowing object for 'fourDimensionRates' === 2016-01-04 14:11:56,742 [5-23] DEBUG PoolableDataSourceFactory - Activated DataSource 44 of type 'fourDimensionRates' === 2016-01-04 14:11:56,744 [5-23] DEBUG DSRequest - Caching instance 44 of DS 'fourDimensionRates' from DSRequest.getDataSource() === 2016-01-04 14:11:56,744 [5-23] DEBUG DSRequest - Caching instance 44 of DS fourDimensionRates === 2016-01-04 14:11:56,745 [5-23] DEBUG RPCManager - Request #1 (DSRequest) payload: { criteria:{ operator:"and", _constructor:"AdvancedCriteria", criteria:[ { fieldName:"RATE_SET_CD", operator:"equals", value:"TIER DS1 IP" }, { fieldName:"EFFECTIVE_DT", operator:"equals", value:new Date(1451883600000) }, { fieldName:"RC3_OCCUR_NO", operator:"iContains", value:"1" }, { fieldName:"RC4_OCCUR_NO", operator:"iContains", value:"1" }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RC2_OCCUR_NO", operator:"equals", value:4 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:3 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:2 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:1 } ] }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RC1_OCCUR_NO", operator:"equals", value:2 }, { fieldName:"RC1_OCCUR_NO", operator:"equals", value:1 } ] }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RATE_SET_CD", operator:"iContains", value:"TIER DS1 IP" } ] } ] }, operationConfig:{ dataSource:"fourDimensionRates", repo:null, operationType:"fetch", textMatchStyle:"exact" }, useStrictJSON:true, appID:"builtinApplication", operation:"fourDimensionRates_fetch", oldValues:{ operator:"and", _constructor:"AdvancedCriteria", criteria:[ { fieldName:"RATE_SET_CD", operator:"equals", value:"TIER DS1 IP" }, { fieldName:"EFFECTIVE_DT", operator:"equals", value:new Date(1451883600000) }, { fieldName:"RC3_OCCUR_NO", operator:"iContains", value:"1" }, { fieldName:"RC4_OCCUR_NO", operator:"iContains", value:"1" }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RC2_OCCUR_NO", operator:"equals", value:4 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:3 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:2 }, { fieldName:"RC2_OCCUR_NO", operator:"equals", value:1 } ] }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RC1_OCCUR_NO", operator:"equals", value:2 }, { fieldName:"RC1_OCCUR_NO", operator:"equals", value:1 } ] }, { _constructor:"AdvancedCriteria", operator:"or", criteria:[ { fieldName:"RATE_SET_CD", operator:"iContains", value:"TIER DS1 IP" } ] } ] } } === 2016-01-04 14:11:56,746 [5-23] INFO IDACall - Performing 1 operation(s) === 2016-01-04 14:11:56,746 [5-23] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null === 2016-01-04 14:11:56,746 [5-23] DEBUG DeclarativeSecurity - DataSource fourDimensionRates is not in the pre-checked list, processing... === 2016-01-04 14:11:56,746 [5-23] DEBUG AppBase - [builtinApplication.fourDimensionRates_fetch] No userTypes defined, allowing anyone access to all operations for this application === 2016-01-04 14:11:56,746 [5-23] DEBUG AppBase - [builtinApplication.fourDimensionRates_fetch] No public zero-argument method named '_fourDimensionRates_fetch' found, performing generic datasource operation === 2016-01-04 14:11:56,747 [5-23] INFO SQLDataSource - [builtinApplication.fourDimensionRates_fetch] Performing fetch operation with criteria: {criteria:[{fieldName:"RATE_SET_CD",operator:"equals",value:"TIER DS1 IP"},{fieldName:"EFFECTIVE_DT",operator:"equals",value:new Date(1451883600000)},{fieldName:"RC3_OCCUR_NO",operator:"iContains",value:"1"},{fieldName:"RC4_OCCUR_NO",operator:"iContains",value:"1"},{criteria:[{fieldName:"RC2_OCCUR_NO",operator:"equals",value:4},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:3},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:2},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:1}],operator:"or"},{criteria:[{fieldName:"RC1_OCCUR_NO",operator:"equals",value:2},{fieldName:"RC1_OCCUR_NO",operator:"equals",value:1}],operator:"or"},{criteria:[{fieldName:"RATE_SET_CD",operator:"iContains",value:"TIER DS1 IP"}],operator:"or"}],operator:"and",_constructor:"AdvancedCriteria"} values: {criteria:[{fieldName:"RATE_SET_CD",operator:"equals",value:"TIER DS1 IP"},{fieldName:"EFFECTIVE_DT",operator:"equals",value:new Date(1451883600000)},{fieldName:"RC3_OCCUR_NO",operator:"iContains",value:"1"},{fieldName:"RC4_OCCUR_NO",operator:"iContains",value:"1"},{criteria:[{fieldName:"RC2_OCCUR_NO",operator:"equals",value:4},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:3},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:2},{fieldName:"RC2_OCCUR_NO",operator:"equals",value:1}],operator:"or"},{criteria:[{fieldName:"RC1_OCCUR_NO",operator:"equals",value:2},{fieldName:"RC1_OCCUR_NO",operator:"equals",value:1}],operator:"or"},{criteria:[{fieldName:"RATE_SET_CD",operator:"iContains",value:"TIER DS1 IP"}],operator:"or"}],operator:"and",_constructor:"AdvancedCriteria"} === 2016-01-04 14:11:56,783 [5-23] INFO SQLDataSource - [builtinApplication.fourDimensionRates_fetch] 44: Executing SQL query on 'Oracle': Select rcs.RATE_SET_CD, rcs.RC1_OCCUR_NO, rcs.RC2_OCCUR_NO, rcs.RC3_OCCUR_NO, rcs.RC4_OCCUR_NO, rcs.ROWNUMBER, rates.RATE_NO, rates.RATE_AMT, rates.RATE_AMT_CD, rates.BEG_EFF_DT, rates.END_EFF_DT from (Select rate.Rate_no, rate.rate_amt, rate.rate_amt_cd, rate.beg_eff_dt, rate.end_eff_dt from rate where rate.rate_tbl_id = (select rate_tbl_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and beg_eff_dt <= TO_DATE('2016-01-04 00:00:00','YYYY-MM-DD HH24:MI:SS') and end_eff_dt >= TO_DATE('2016-01-04 00:00:00','YYYY-MM-DD HH24:MI:SS') order by rate.rate_no, rate.beg_eff_dt) rates, (Select 'TIER DS1 IP' as RATE_SET_CD, rc1_occur_no, rc2_occur_no, rc3_occur_no, rc4_occur_no, Rownum rownumber from (select rc1.rc_occur_no rc1_occur_no, rc2.rc_occur_no rc2_occur_no, rc3.rc_occur_no rc3_occur_no, rc4.rc_occur_no rc4_occur_no From rc_rng rc1 ,rc_rng rc2 ,rc_rng rc3 ,rc_rng rc4 where rc1.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 1) and rc2.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 2) and rc3.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 3) and rc4.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 4) order by rc1.rc_occur_no ,rc2.rc_occur_no ,rc3.rc_occur_no ,rc4.rc_occur_no )) rcs Where rcs.rownumber = rates.rate_no (+) and (((rcs.RATE_SET_CD = 'TIER DS1 IP' AND rcs.RATE_SET_CD IS NOT NULL) AND '1'='1' AND (LOWER(rcs.RC3_OCCUR_NO) LIKE LOWER('%1%') ESCAPE'\' AND rcs.RC3_OCCUR_NO IS NOT NULL) AND (LOWER(rcs.RC4_OCCUR_NO) LIKE LOWER('%1%') ESCAPE'\' AND rcs.RC4_OCCUR_NO IS NOT NULL) AND ((rcs.RC2_OCCUR_NO = 4 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 3 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 2 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 1 AND rcs.RC2_OCCUR_NO IS NOT NULL)) AND ((rcs.RC1_OCCUR_NO = 2 AND rcs.RC1_OCCUR_NO IS NOT NULL) OR (rcs.RC1_OCCUR_NO = 1 AND rcs.RC1_OCCUR_NO IS NOT NULL)) AND ((LOWER(rcs.RATE_SET_CD) LIKE LOWER('%tier ds1 ip%') ESCAPE'\' AND rcs.RATE_SET_CD IS NOT NULL)))) order by rcs.rownumber === 2016-01-04 14:11:56,784 [5-23] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fourDimensionRates_fetch] Executing pingTest 'select 1 from dual' on connection 886671440 === 2016-01-04 14:11:56,843 [5-23] DEBUG SQLConnectionManager - [builtinApplication.fourDimensionRates_fetch] Borrowed connection '886671440' === 2016-01-04 14:11:56,843 [5-23] DEBUG SQLTransaction - [builtinApplication.fourDimensionRates_fetch] Started new Oracle transaction "886671440" === 2016-01-04 14:11:56,843 [5-23] DEBUG SQLDriver - [builtinApplication.fourDimensionRates_fetch] About to execute SQL query in 'Oracle' using connection '886671440' === 2016-01-04 14:11:56,843 [5-23] INFO SQLDriver - [builtinApplication.fourDimensionRates_fetch] Executing SQL query on 'Oracle': Select rcs.RATE_SET_CD, rcs.RC1_OCCUR_NO, rcs.RC2_OCCUR_NO, rcs.RC3_OCCUR_NO, rcs.RC4_OCCUR_NO, rcs.ROWNUMBER, rates.RATE_NO, rates.RATE_AMT, rates.RATE_AMT_CD, rates.BEG_EFF_DT, rates.END_EFF_DT from (Select rate.Rate_no, rate.rate_amt, rate.rate_amt_cd, rate.beg_eff_dt, rate.end_eff_dt from rate where rate.rate_tbl_id = (select rate_tbl_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and beg_eff_dt <= TO_DATE('2016-01-04 00:00:00','YYYY-MM-DD HH24:MI:SS') and end_eff_dt >= TO_DATE('2016-01-04 00:00:00','YYYY-MM-DD HH24:MI:SS') order by rate.rate_no, rate.beg_eff_dt) rates, (Select 'TIER DS1 IP' as RATE_SET_CD, rc1_occur_no, rc2_occur_no, rc3_occur_no, rc4_occur_no, Rownum rownumber from (select rc1.rc_occur_no rc1_occur_no, rc2.rc_occur_no rc2_occur_no, rc3.rc_occur_no rc3_occur_no, rc4.rc_occur_no rc4_occur_no From rc_rng rc1 ,rc_rng rc2 ,rc_rng rc3 ,rc_rng rc4 where rc1.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 1) and rc2.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 2) and rc3.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 3) and rc4.rc_type_id = (select rc_type_id from rc_comb where rc_comb_id = (select rc_comb_id from rate_set_def where rate_set_cd = 'TIER DS1 IP') and rc_seq_no = 4) order by rc1.rc_occur_no ,rc2.rc_occur_no ,rc3.rc_occur_no ,rc4.rc_occur_no )) rcs Where rcs.rownumber = rates.rate_no (+) and (((rcs.RATE_SET_CD = 'TIER DS1 IP' AND rcs.RATE_SET_CD IS NOT NULL) AND '1'='1' AND (LOWER(rcs.RC3_OCCUR_NO) LIKE LOWER('%1%') ESCAPE'\' AND rcs.RC3_OCCUR_NO IS NOT NULL) AND (LOWER(rcs.RC4_OCCUR_NO) LIKE LOWER('%1%') ESCAPE'\' AND rcs.RC4_OCCUR_NO IS NOT NULL) AND ((rcs.RC2_OCCUR_NO = 4 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 3 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 2 AND rcs.RC2_OCCUR_NO IS NOT NULL) OR (rcs.RC2_OCCUR_NO = 1 AND rcs.RC2_OCCUR_NO IS NOT NULL)) AND ((rcs.RC1_OCCUR_NO = 2 AND rcs.RC1_OCCUR_NO IS NOT NULL) OR (rcs.RC1_OCCUR_NO = 1 AND rcs.RC1_OCCUR_NO IS NOT NULL)) AND ((LOWER(rcs.RATE_SET_CD) LIKE LOWER('%tier ds1 ip%') ESCAPE'\' AND rcs.RATE_SET_CD IS NOT NULL)))) order by rcs.rownumber === 2016-01-04 14:11:57,169 [5-23] INFO DSResponse - [builtinApplication.fourDimensionRates_fetch] DSResponse: List with 16 items === 2016-01-04 14:11:57,170 [5-23] DEBUG DSRequest - freeOnExecute is false for request of type fetch on DataSource fourDimensionRates - not freeing resources! === 2016-01-04 14:11:57,170 [5-23] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2016-01-04 14:11:57,170 [5-23] DEBUG SQLTransaction - Committing Oracle transaction "886671440" === 2016-01-04 14:11:57,228 [5-23] DEBUG RPCManager - non-DMI response, dropExtraFields: false === 2016-01-04 14:11:57,230 [5-23] DEBUG SQLTransaction - getConnection() found transactional connection for Oracle with hashcode "886671440" === 2016-01-04 14:11:57,230 [5-23] DEBUG SQLTransaction - Ending Oracle transaction "886671440" === 2016-01-04 14:11:57,288 [5-23] DEBUG SQLConnectionManager - About to close connection with hashcode "886671440" === 2016-01-04 14:11:57,288 [5-23] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 886671440 === 2016-01-04 14:11:57,347 [5-23] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 44 of type 'fourDimensionRates'