Announcement

Collapse
No announcement yet.
X
  • Filter
  • Time
Clear All
new posts

    STATUS_TRANSPORT_ERROR response code.

    we are getting STATUS_TRANSPORT_ERROR very frequently after shifting to 6.0.X version. This error is not consistent and when we refresh browser then this error is not reproducible. Is it know issue? may I know which scenarios error will be thrown?

    #2
    I'm pretty sure you are getting timeout or 503 in you server side(maybe because heavy traffic and that is why is random issue), you need to debug your network while running you app, so maybe from the server side, check for 503 errors, or log network errors remotely(create a service to receive app network erors) , save timestamps to figure out the errors in the server side

    Comment


      #3
      Hi James, Thanks for info. I have captured server side logs when this error has happened. Note : please copy below logs to txt file and verify.
      we have observed that "DataSource Creation Count: " keep on increasing (please search with 'DataSource Creation Count:') . we have observed that when the count is reached to 517, same data-source is using for different threads (please search with 'DataSource Creation Count: 517'). and the same time this error has happend. after some time, it is skipped 518 datasource and again multiple datasource for 519. then it incremented properly again.

      we want to know why same datasource is using after upgraded to samrtGwt version? what precautions we can take to avoid that? Please let us know.

      @@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,660 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@40bb6d21
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,660 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-9
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,660 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,660 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 499
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,660 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,665 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@e9ce4ce
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,665 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-7
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,665 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,665 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 500
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,665 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,877 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@53cf7262
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,877 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,877 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,877 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 501
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,877 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,884 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@5bbf4233
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,884 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-9
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,884 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,884 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 502
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:10,884 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:11,384 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@4451bab7
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:11,384 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-7
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:11,384 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:11,384 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 503
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:11,384 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:12,311 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@3bc485fd
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:12,311 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-9
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:12,311 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:12,311 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 504
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:12,311 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:24,796 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@3bbf678e
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:24,796 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportClauseManager] Report Parameters: {DESTINATION_LEVEL=Airport, REPORT_TYPE=AGGREGATE, dateRangeLogger=Yearly, origin=CDG,BOM, destination=, SHOW_FAREANDREVENUETAX=true, ORIGIN_LEVEL=Airport, destinationCriteriaType=NONE, USER_CD=dga111991, dataLoadType=128,, destinationType=Airport, clientId=284, productId=NA_ADB, toMonth=2513, ResultType=GRID, PRODUCT_ID=NA_ADB, originType=Airport, MODULE=OD, directionType=DIR, PRODUCTS=,AIRPORT, subModule=OD, dateRangeType=Annual, originCriteriaType=NONE, fromMonth=2502, dataSource=GDD}
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:24,806 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Report Query: reportOdGdd-directionalQuery:SELECT * FROM( 'Query') ORDER BY BOOKINGS DESC
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,263 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,263 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,263 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 505
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,276 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] reportOdGdd-directionalQuery:SELECT * FROM( 'Query') ORDER BY BOOKINGS DESC
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,276 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: Report returns 2 rows
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:36:43,276 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: fetch took 18 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:16,959 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@1634d816
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:16,959 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:16,959 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:16,959 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 506
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:16,959 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,003 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@15189392
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,003 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,003 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,004 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 507
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,004 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,180 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@4e07b2b
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,180 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,180 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,180 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 508
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:20,180 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:23,693 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@4a803666
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:23,693 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:23,693 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:23,693 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 509
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:23,693 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,108 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@43006528
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,108 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,108 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,108 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 510
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,108 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,632 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@22885f1a
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,632 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,632 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,632 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 511
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:24,632 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,512 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@2ccef81b
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,512 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,512 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,512 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 512
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,512 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,582 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@2ea08f2d
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,582 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,582 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 30
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,582 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 513
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:27,582 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:38:36,953 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@1986537
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:38:36,953 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportClauseManager] Report Parameters: {DESTINATION_LEVEL=Airport, REPORT_TYPE=TREND, airlineType=Operating, dateRangeLogger=Monthly, connectType=Airport, origin=CTA, destination=ALL, DISTANCE_SELECTION=true, ORIGIN_LEVEL=Airport, destinationCriteriaType=NONE, USER_CD=mla000940, dataLoadType=128,, destinationType=Airport, airline=ALL, connect=ALL, clientId=227, productId=NA_ADB, toMonth=2526, PRODUCT_ID=NA_ADB, odAdvancedType=All Connection Via, originType=Airport, MODULE=OD, directionType=NDIR-Split, PRODUCTS=,AIRPORT, subModule=OD, connectAirline=ALL, dateRangeType=Monthly, HAS_MARKET=true, originCriteriaType=NONE, fromMonth=2515, connectAirlineType=Operating, dataSource=GDD}
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:38:36,970 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Report Query: reportOdGdd-nonDirectionalQuery:SELECT * FROM('Query' ) ORDER BY YEAR, MONTH, BOOKINGS DESC
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:42,789 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@3755985a
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:42,789 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportClauseManager] Report Parameters: {DESTINATION_LEVEL=Airport, REPORT_TYPE=AGGREGATE, POO_LEVEL=Country, dateRangeLogger=Yearly, origin=IN,, destination=, pointOfOriginType=Country, SHOW_FAREANDREVENUETAX=true, ORIGIN_LEVEL=Country, destinationCriteriaType=NONE, USER_CD=dga111991, dataLoadType=128,, destinationType=Airport, clientId=284, productId=NA_ADB, toMonth=2513, ResultType=GRID, PRODUCT_ID=NA_ADB, originType=Country, MODULE=OD, directionType=DIR, PRODUCTS=,AIRPORT, subModule=OD, dateRangeType=Annual, originCriteriaType=NONE, fromMonth=2502, pointOfOrigin=ALL, dataSource=GDD}
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:38:42,851 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Report Query: reportOdGdd-directionalQuery:SELECT * FROM( 'Query') ORDER BY BOOKINGS DESC
      2017-05-31 07:38:46,948 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] UserName Retrieval Successful: xx555555
      2017-05-31 07:38:46,948 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] Configuartion Values sgmarketint
      2017-05-31 07:38:46,950 INFO [org.apache.cxf.service.factory.ReflectionServiceFactoryBean] Creating Service {http://sabre.com/apd/scp/spml/v2008.0}spml from WSDL: http://community.internal.sabre.com/...s/v2008_0?wsdl
      2017-05-31 07:38:46,997 INFO [org.apache.cxf.interceptor.LoggingOutInterceptor] Outbound Message
      ---------------------------
      ID: 15
      Address: http://community.internal.sabre.com/...rvices/v2008_0
      Encoding: UTF-8
      Content-Type: text/xml
      Headers: {SOAPAction=["http://sabre.com/apd/scp/spml/v2008.0/SPMLLookupRequest"], Accept=[*/*]}
      Payload: <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/"><soap:Header><wsse:Security xmlns:wsse="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-secext-1.0.xsd" soap:mustUnderstand="1"><wsse:UsernameToken xmlns:wsse="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-secext-1.0.xsd" xmlns:wsu="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-utility-1.0.xsd" wsu:Id="UsernameToken-15"><wsse:Username>sgmarketint</wsse:Username><wsse:Password Type="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-username-token-profile-1.0#PasswordText">2012Market</wsse:Password></wsse:UsernameToken></wsse:Security></soap:Header><soap:Body><lookupRequest xmlns="urn:oasis:names:tc:SPML:2:0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:password" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns5="http://sabre.com/apd/scp/user/v2008.0" returnData="data"><psoID ID="User/xx555555" targetID="scp"/></lookupRequest></soap:Body></soap:Envelope>
      --------------------------------------
      2017-05-31 07:38:47,095 INFO [org.apache.cxf.interceptor.LoggingInInterceptor] Inbound Message
      ----------------------------
      ID: 15
      Response-Code: 200
      Encoding: UTF-8
      Content-Type: text/xml;charset=UTF-8
      Headers: {Server=[Apache-Coyote/1.1], Set-Cookie=[BIGipServeremg-eport-mc-app0001-community.prod_pool_2221=1662507018.44296.0000; expires=Wed, 31-May-2017 15:38:47 GMT; path=/, JSESSIONID=5D956BCEEF1D3E5AAEF24DE96F17CCD7; Path=/emergo/; HttpOnly], Vary=[Accept-Encoding], content-type=[text/xml;charset=UTF-8], Proxy-Connection=[Keep-Alive], Content-Length=[2307], Date=[Wed, 31 May 2017 12:38:46 GMT], via=[insertA]}
      Payload: <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/"><soap:Body><lookupResponse xmlns="urn:oasis:names:tc:SPML:2:0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:password" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns5="http://sabre.com/apd/scp/user/v2008.0" status="success" requestID="14b90cb6-a71c-4c14-8a9b-0700a40ec25e"><pso><psoID targetID="scp" ID="User/xx555555"/><data><ns5:User><ns5:EmployeeNumber>555555</ns5:EmployeeNumber><ns5:CompanyIATACode>XX</ns5:CompanyIATACode><ns5:FirstName>Kim</ns5:FirstName><ns5:LastName>Gesch</ns5:LastName><ns5:PhoneNumber><ns5:CountryCode>972</ns5:CountryCode><ns5:Number>9728741368</ns5:Number></ns5:PhoneNumber><ns5:Email>kim.gesch@sabre.com</ns5:Email><ns5:CityCode>Flo</ns5:CityCode><ns5:Product><ns5:Keyword>MarketIntelligence</ns5:Keyword><ns5:AppInfo><p:UserProductConfiguration xmlns:p="http://mi.sabre.com/sabre/apd/mi/version2012" xmlns="http://sabre.com/apd/scp/user/v2008.0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:password" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://mi.sabre.com/sabre/apd/mi/version2012 http://emergoz2.dev.sabre.com:20011/emergo/content/product/AVCOMM/158582054/160733981/sso-roles.xsd">
      <p:host>1S</p:host>
      <p:startDate>2016-01-20</p:startDate>
      <p:endDate>2018-12-31</p:endDate>
      <p:role>USER</p:role>
      <p:dataSource>GDD</p:dataSource>
      <p:products>
      <p:product>AIRPORT</p:product>
      </p:products>
      <p:modules>
      <p:module>OD</p:module>
      <p:module>LEG</p:module>
      <p:module>FUTURE_BOOKING</p:module>
      <p:module>POS</p:module>
      <p:module>FLOW</p:module>
      <p:module>SCHEDULES</p:module>
      <p:module>ALERTS</p:module>
      <p:module>FORWARD_MARKET</p:module>
      <p:module>SELFSERVICE</p:module>
      </p:modules>
      </p:UserProductConfiguration></ns5:AppInfo><ns5:Release><ns5:Version>5.11.0</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>Community Portal</ns5:Keyword><ns5:Role>End User</ns5:Role><ns5:Release><ns5:Version>1.0</ns5:Version></ns5:Release></ns5:Product></ns5:User></data></pso></lookupResponse></soap:Body></soap:Envelope>
      --------------------------------------
      2017-05-31 07:38:47,096 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] User Lookup Successful
      2017-05-31 07:38:47,099 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] User Product Configuration Successful: Client ID: null
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,439 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@30386d26
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,776 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,776 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,776 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,777 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] preferences-reportDisplayPreferencesQuery:SELECT PREF_CATEGORY_ATTRIBUTE,VALUE FROM DIM_PREF_ATTRIBUTE , PREF_ATTRIBUTE_VALUE, DIM_MODULE WHERE (LOWER(DIM_MODULE.MODULE_NAME)='common' AND LOWER(USER_ID)='xx555555' AND CLIENT_ID=2) AND DIM_PREF_ATTRIBUTE.PREF_ATTRIBUTE_ID = PREF_ATTRIBUTE_VALUE.PREF_ATTRIBUTE_ID AND PREF_ATTRIBUTE_VALUE.MODULE_ID = DIM_MODULE.MODULE_ID
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,777 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: Report returns 5 rows
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:47,777 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: fetch took 0 seconds.
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,272 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@71a83ff3
      2017-05-31 07:38:48,298 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDateRange with Arguments: 2NA_ADBtruecom.isomorphic.datasource.DSRequest@5d34587bPreliminary
      2017-05-31 07:38:48,299 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:2 is NULL. Loading DateRange into DateRange Cache.
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,303 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-7
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,303 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,303 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,304 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] preferences-reportDisplayPreferencesQuery:SELECT PREF_CATEGORY_ATTRIBUTE,VALUE FROM DIM_PREF_ATTRIBUTE , PREF_ATTRIBUTE_VALUE, DIM_MODULE WHERE (LOWER(DIM_MODULE.MODULE_NAME)='od' AND LOWER(USER_ID)='xx555555' AND CLIENT_ID=2) AND DIM_PREF_ATTRIBUTE.PREF_ATTRIBUTE_ID = PREF_ATTRIBUTE_VALUE.PREF_ATTRIBUTE_ID AND PREF_ATTRIBUTE_VALUE.MODULE_ID = DIM_MODULE.MODULE_ID
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,304 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: Report returns 23 rows
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:48,304 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: fetch took 0 seconds.
      2017-05-31 07:38:48,348 INFO [org.apache.jcs.engine.CacheEventQueue] Cache event queue created: CacheEventQueue[listenerId=1579272113, cacheName="odDataRangeCache"]
      2017-05-31 07:38:48,349 INFO [org.apache.jcs.engine.CacheEventQueue] Cache event queue created: CacheEventQueue[listenerId=1579272113, cacheName="odDataRangeCache"]
      2017-05-31 07:38:48,349 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:2 loaded from Database into Date Range Cache.
      2017-05-31 07:38:48,349 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange returned for Client:2 contains 254 elements
      2017-05-31 07:38:48,349 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      2017-05-31 07:38:48,349 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 35
      2017-05-31 07:38:48,349 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      2017-05-31 07:38:48,349 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDateRange took 0 seconds.
      2017-05-31 07:38:48,498 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDateRange with Arguments: 2NA_ADBtruecom.isomorphic.datasource.DSRequest@2ff23bd0Preliminary
      2017-05-31 07:38:48,498 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:2 is NULL. Loading DateRange into DateRange Cache.
      2017-05-31 07:38:48,543 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:2 loaded from Database into Date Range Cache.
      2017-05-31 07:38:48,543 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange returned for Client:2 contains 254 elements
      2017-05-31 07:38:48,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-7
      2017-05-31 07:38:48,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 35
      2017-05-31 07:38:48,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      2017-05-31 07:38:48,543 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDateRange took 0 seconds.
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,510 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@d6b424e
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 35
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,543 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,544 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] preferences-reportDisplayPreferencesQuery:SELECT PREF_CATEGORY_ATTRIBUTE,VALUE FROM DIM_PREF_ATTRIBUTE , PREF_ATTRIBUTE_VALUE, DIM_MODULE WHERE (LOWER(DIM_MODULE.MODULE_NAME)='abk' AND LOWER(USER_ID)='xx555555' AND CLIENT_ID=2) AND DIM_PREF_ATTRIBUTE.PREF_ATTRIBUTE_ID = PREF_ATTRIBUTE_VALUE.PREF_ATTRIBUTE_ID AND PREF_ATTRIBUTE_VALUE.MODULE_ID = DIM_MODULE.MODULE_ID
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,544 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: Report returns 18 rows
      xx555555 B88A32388E2C79C5BB955B4D2AD63839 2017-05-31 07:38:50,544 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: fetch took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,757 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-9
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,757 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,757 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,767 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] reportOdGdd-directionalQuery:SELECT * FROM( 'Query') ORDER BY BOOKINGS DESC
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,767 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: Report returns 223 rows
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:39:38,767 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: fetch took 55 seconds.
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,642 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-6
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,642 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,643 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 517
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,666 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] reportOdGdd-nonDirectionalQuery:SELECT * FROM( 'Query') ORDER BY YEAR, MONTH, BOOKINGS DESC
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,667 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: Report returns -1 rows
      mla000940 7A37B777A40E683B7F919B55B01E3427 2017-05-31 07:40:16,667 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] After Method: fetch took 99 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:44:56,124 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@1ee39de8
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:44:56,124 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportClauseManager] Report Parameters: {DESTINATION_LEVEL=Airport, REPORT_TYPE=AGGREGATE, airlineType=Operating, dateRangeLogger=Yearly, origin=CDG, destination=PTY, legOrigin=CDG, SHOW_FAREANDREVENUETAX=true, legOriginType=Airport, SHOW_PRORATION=SQUARE_ROOT, legDestination=PTY, destinationCriteriaType=NONE, ORIGIN_LEVEL=Airport, USER_CD=dga111991, dataLoadType=128,, destinationType=Airport, airline=AF, clientId=284, productId=NA_ADB, LEG_ORIGIN_LEVEL=Airport, toMonth=2513, ResultType=GRID, PRODUCT_ID=NA_ADB, originType=Airport, MODULE=LEG, directionType=DIR, PRODUCTS=,AIRPORT, AGGREGATE_SELECTION=true, subModule=Flow Analysis, dateRangeType=Annual, HAS_MARKET=true, originCriteriaType=NONE, fromMonth=2502, dataSource=GDD, LEG_DESTINATION_LEVEL=Airport, legDestinationType=Airport}
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:44:56,136 TRACE [com.sabre.apd.mi.smartgwt.server.reports.ReportsDMI] Report Query: reportLegFlow-directionalOptimizedQuery:WITH fct_joined AS ( 'Query') ORDER BY BOOKINGS DESC
      2017-05-31 07:45:49,886 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] UserName Retrieval Successful: 9w000123
      2017-05-31 07:45:49,886 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] Configuartion Values sgmarketint
      2017-05-31 07:45:49,888 INFO [org.apache.cxf.service.factory.ReflectionServiceFactoryBean] Creating Service {http://sabre.com/apd/scp/spml/v2008.0}spml from WSDL: http://community.internal.sabre.com/...s/v2008_0?wsdl
      2017-05-31 07:45:49,951 INFO [org.apache.cxf.interceptor.LoggingOutInterceptor] Outbound Message
      ---------------------------
      ID: 16
      Address: http://community.internal.sabre.com/...rvices/v2008_0
      Encoding: UTF-8
      Content-Type: text/xml
      Headers: {SOAPAction=["http://sabre.com/apd/scp/spml/v2008.0/SPMLLookupRequest"], Accept=[*/*]}
      Payload: <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/"><soap:Header><wsse:Security xmlns:wsse="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-secext-1.0.xsd" soap:mustUnderstand="1"><wsse:UsernameToken xmlns:wsse="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-secext-1.0.xsd" xmlns:wsu="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-wssecurity-utility-1.0.xsd" wsu:Id="UsernameToken-16"><wsse:Username>sgmarketint</wsse:Username><wsse:Password Type="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-username-token-profile-1.0#PasswordText">2012Market</wsse:Password></wsse:UsernameToken></wsse:Security></soap:Header><soap:Body><lookupRequest xmlns="urn:oasis:names:tc:SPML:2:0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:password" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns5="http://sabre.com/apd/scp/user/v2008.0" returnData="data"><psoID ID="User/9w000123" targetID="scp"/></lookupRequest></soap:Body></soap:Envelope>
      --------------------------------------
      2017-05-31 07:45:50,054 INFO [org.apache.cxf.interceptor.LoggingInInterceptor] Inbound Message
      ----------------------------
      ID: 16
      Response-Code: 200
      Encoding: UTF-8
      Content-Type: text/xml;charset=UTF-8
      Headers: {Server=[Apache-Coyote/1.1], Set-Cookie=[BIGipServeremg-eport-mc-app0001-community.prod_pool_2221=1662507018.44296.0000; expires=Wed, 31-May-2017 15:45:50 GMT; path=/, JSESSIONID=D0EBB906A8A77C79972E20D8B92823FB; Path=/emergo/; HttpOnly], Vary=[Accept-Encoding], content-type=[text/xml;charset=UTF-8], Proxy-Connection=[Keep-Alive], Content-Length=[5311], Date=[Wed, 31 May 2017 12:45:50 GMT], via=[insertA]}
      Payload: <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/"><soap:Body><lookupResponse xmlns="urn:oasis:names:tc:SPML:2:0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:password" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns5="http://sabre.com/apd/scp/user/v2008.0" status="success" requestID="013bb5cf-982e-4c21-8195-a806a0faea73"><pso><psoID targetID="scp" ID="User/9w000123"/><data><ns5:User><ns5:EmployeeNumber>000123</ns5:EmployeeNumber><ns5:CompanyIATACode>9W</ns5:CompanyIATACode><ns5:FirstName>Jet</ns5:FirstName><ns5:LastName>Demo</ns5:LastName><ns5:PhoneNumber><ns5:CountryCode>91</ns5:CountryCode><ns5:Number>9686604121</ns5:Number></ns5:PhoneNumber><ns5:Email>jet@jetairways.com</ns5:Email><ns5:CityCode>BOM</ns5:CityCode><ns5:Product><ns5:Keyword>Auto Seating</ns5:Keyword><ns5:Release><ns5:Version>kk</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>8.13.9</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>Inventory Analytics</ns5:Keyword><ns5:Release><ns5:Version>Codeshare</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>Frequent Traveler</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>Passenger Name Record</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>Air Pricing</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>Siebel eService</ns5:Keyword><ns5:Release><ns5:Version>8.1</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>8.1 - Documentation</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>MarketIntelligence</ns5:Keyword><ns5:AppInfo><p:UserProductConfiguration xmlns:p="http://mi.sabre.com/sabre/apd/mi/version2012" xmlns="http://sabre.com/apd/scp/user/v2008.0" xmlns:ns2="urn:oasis:names:tc:SPML:2:0:search" xmlns:ns3="urn:oasis:names:tc:SPML:2:0:suspend" xmlns:ns4="urn:oasis:names:tc:SPML:2:0:password" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://mi.sabre.com/sabre/apd/mi/version2012 http://emergoz2.dev.sabre.com:20011/emergo/content/product/AVCOMM/158582054/160733981/sso-roles.xsd">
      <p:host>9W</p:host>
      <p:startDate>2015-11-29</p:startDate>
      <p:endDate>2019-12-30</p:endDate>
      <p:role>USER</p:role>
      <p:dataSource>GDD</p:dataSource>
      <p:products>
      <p:product>NETWORK_ANALYZER</p:product>
      </p:products>
      <p:modules>
      <p:module>OD</p:module>
      <p:module>LEG</p:module>
      <p:module>POS</p:module>
      <p:module>FLOW</p:module>
      <p:module>SCHEDULES</p:module>
      <p:module>ALERTS</p:module>
      <p:module>CLIENT_ADMIN</p:module>
      <p:module>SELFSERVICE</p:module>
      <p:module>FORWARD_MARKET</p:module>
      </p:modules>
      </p:UserProductConfiguration></ns5:AppInfo><ns5:Release><ns5:Version>5.11.0</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>SabreSonic Interact</ns5:Keyword><ns5:Release><ns5:Version>5.5 - Community </ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.4.1 ARINC Only</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.7 - Community PreRelease 2</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.4 - Community PreRelease</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.3.0.1</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>Test New Qik exec 2012.0.8</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.7 - Community PreRelease</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.3.0.3</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.2.1 - Community</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.2 - Community</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.3 - Community PreRelease</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.7 - Community</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.4 - Community</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>5.3.1 - Community</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>SabreSonic Ticket</ns5:Keyword><ns5:Release><ns5:Version>A Electronic Ticketing</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>Automated Exchange and Refund (Archives)</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release><ns5:Release><ns5:Version>H Offline Files</ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>DummyApp</ns5:Keyword><ns5:Release><ns5:Version>5.3 - Community PreRelease </ns5:Version><ns5:State>ACTIVE</ns5:State></ns5:Release></ns5:Product><ns5:Product><ns5:Keyword>Community Portal</ns5:Keyword><ns5:Role>End User</ns5:Role><ns5:Release><ns5:Version>1.0</ns5:Version></ns5:Release></ns5:Product></ns5:User></data></pso></lookupResponse></soap:Body></soap:Envelope>
      --------------------------------------
      2017-05-31 07:45:50,057 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] User Lookup Successful
      2017-05-31 07:45:50,061 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.SsoDMI] User Product Configuration Successful: Client ID: null
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,513 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@3962f2bf
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,536 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-5
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,536 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,536 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 519
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,537 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] preferences-reportDisplayPreferencesQuery:SELECT PREF_CATEGORY_ATTRIBUTE,VALUE FROM DIM_PREF_ATTRIBUTE , PREF_ATTRIBUTE_VALUE, DIM_MODULE WHERE (LOWER(DIM_MODULE.MODULE_NAME)='common' AND LOWER(USER_ID)='9w000123' AND CLIENT_ID=18) AND DIM_PREF_ATTRIBUTE.PREF_ATTRIBUTE_ID = PREF_ATTRIBUTE_VALUE.PREF_ATTRIBUTE_ID AND PREF_ATTRIBUTE_VALUE.MODULE_ID = DIM_MODULE.MODULE_ID
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,537 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: Report returns 5 rows
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:50,537 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: fetch took 0 seconds.
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:52,978 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] Before Method: fetch with Arguments: com.isomorphic.datasource.DSRequest@fcec335
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,019 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-5
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,019 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,019 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 519
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,020 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] preferences-reportDisplayPreferencesQuery:SELECT PREF_CATEGORY_ATTRIBUTE,VALUE FROM DIM_PREF_ATTRIBUTE , PREF_ATTRIBUTE_VALUE, DIM_MODULE WHERE (LOWER(DIM_MODULE.MODULE_NAME)='od' AND LOWER(USER_ID)='9w000123' AND CLIENT_ID=18) AND DIM_PREF_ATTRIBUTE.PREF_ATTRIBUTE_ID = PREF_ATTRIBUTE_VALUE.PREF_ATTRIBUTE_ID AND PREF_ATTRIBUTE_VALUE.MODULE_ID = DIM_MODULE.MODULE_ID
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,020 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: Report returns 23 rows
      9w000123 BC39F6DB79DB30EE130DF80DD6739C28 2017-05-31 07:45:53,020 TRACE [com.sabre.apd.mi.smartgwt.server.dmi.PreferencesDMI] After Method: fetch took 0 seconds.
      2017-05-31 07:45:53,532 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDateRange with Arguments: 18NA_ADBtruecom.isomorphic.datasource.DSRequest@377ef8f7Preliminary
      2017-05-31 07:45:53,532 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:18 is NULL. Loading DateRange into DateRange Cache.
      2017-05-31 07:45:53,569 INFO [org.apache.jcs.engine.CacheEventQueue] Cache event queue created: CacheEventQueue[listenerId=1579272113, cacheName="odDataRangeCache"]
      2017-05-31 07:45:53,569 INFO [org.apache.jcs.engine.CacheEventQueue] Cache event queue created: CacheEventQueue[listenerId=1579272113, cacheName="odDataRangeCache"]
      2017-05-31 07:45:53,569 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:18 loaded from Database into Date Range Cache.
      2017-05-31 07:45:53,569 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange returned for Client:18 contains 76 elements
      2017-05-31 07:45:53,569 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      2017-05-31 07:45:53,569 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 35
      2017-05-31 07:45:53,569 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 519
      2017-05-31 07:45:53,569 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDateRange took 0 seconds.
      2017-05-31 07:45:54,156 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDateRange with Arguments: 18NA_ADBtruecom.isomorphic.datasource.DSRequest@3ffcc137Preliminary
      2017-05-31 07:45:54,156 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:18 is NULL. Loading DateRange into DateRange Cache.
      2017-05-31 07:45:54,163 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange for Client:18 loaded from Database into Date Range Cache.
      2017-05-31 07:45:54,163 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] DateRange returned for Client:18 contains 76 elements
      2017-05-31 07:45:54,163 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      2017-05-31 07:45:54,163 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 35
      2017-05-31 07:45:54,163 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 519
      2017-05-31 07:45:54,163 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDateRange took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:21,918 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@7667a7af
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:21,918 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:21,918 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:21,918 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 519
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:21,918 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,087 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@7009834f
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,087 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,087 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,087 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 520
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,087 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,121 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@6a795f9e
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,121 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,121 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,121 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 521
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:22,121 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,781 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@ff8d42b
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,781 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,781 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,781 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] DataSource Creation Count: 522
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,781 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] After Method: getDimensionsList took 0 seconds.
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,957 TRACE [com.sabre.apd.mi.smartgwt.server.cache.CacheManager] Before Method: getDimensionsList with Arguments: com.isomorphic.datasource.DSRequest@4a15dc3e
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,957 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Name: http-2082-11
      dga111991 C081BACAB35AA48EFBDE9E560D8356D9 2017-05-31 07:46:29,957 INFO [com.sabre.apd.mi.smartgwt.server.logger.FileLoggingInterceptor] Thread Active Count: 33

      Comment


        #4
        This log shows no errors. Before trying to analyze anything at this level, get at least basic information about the error: HTTP error code, error message, etc. If it is in fact a 503 error, then the SmartClient server code isn't even involved.

        Comment


          #5
          we are getting mostly hhttp error code is :0 ; sometimes it is giving 502 and 504

          Comment


            #6

            I am getting 'ISC_Core.js:1152 *13:12:36.843:XRP3:WARN:RPCManager:xmlHttpRequest.getAllResponseHeaders() returned null' message from smartgwt response. Please find the attached test file for full error meesages.
            Attached Files

            Comment


              #7
              If you are not aware, you can use Google to find out what these HTTP error codes mean and what may cause them.

              None of these error codes are likely to be due to problems with SmartGWT. These are issues happening before the requests reach the server.

              The warnings you see in the SmartGWT logs are secondary symptoms. Figure out how you're causing these HTTP-level issues and those will likely disappear.

              Comment


                #8
                Hi Isomorphic, Can you please help us understand apart from network issues what can cause ":RPCManager:xmlHttpRequest.getAllResponseHeaders() returned null". Any reference to documentation will be helpful. There is no error or warnings in server logs.

                Comment


                  #9
                  We just covered this - see previous post.

                  Comment


                    #10
                    I just wanted to follow up on this post. I had the same issue but I incorrectly thought that mine was different. I created a new post for it (but shouldn't have, sorry):
                    https://forums.smartclient.com/forum...ased-on-webkit

                    My post describes what I had to do to fix the problem, it was in fact a server side HTTP issue as Isomorphic suggested in this post. The server was closing an HTTP keep-alive socket and not informing the HTTP client of this via the header "Connection: close".

                    Comment

                    Working...
                    X