Using user-defined JAVA_HOME: /usr/lib/jvm/java-6-openjdk-amd64

Setting Tomcat <Connector> property (port 8080): maxThreads=1000
Setting Tomcat <Connector> property (port 8080): maxHeaderCount=200
Setting Tomcat <Connector> property (port 8080): maxKeepAliveRequests=1000
Setting Tomcat <Connector> property (port 8080): maxHttpHeaderSize=65536
=== 2014-03-06 12:22:22,155 [main] INFO  Embedded - Starting tomcat server
=== 2014-03-06 12:22:22,242 [main] INFO  StandardEngine - Starting Servlet Engine: Apache Tomcat/5.5.23
=== 2014-03-06 12:22:22,291 [main] INFO  StandardHost - XML validation disabled
ISC: Configuring log4j from: file:/home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/WEB-INF/embeddedTomcat/../embeddedTomcat/webapps/../../../WEB-INF/classes/log4j.isc.config.xml
=== 2014-03-06 12:22:22,678 [main] INFO  ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
=== 2014-03-06 12:22:22,684 [main] INFO  ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2014-03-06 12:22:22,803 [main] INFO  ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/WEB-INF/embeddedTomcat/../embeddedTomcat/webapps/../../../WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2014-03-06 12:22:22,803 [main] INFO  ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2014-03-06 12:22:22,804 [main] INFO  ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2014-03-06 12:22:22,810 [main] INFO  ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/WEB-INF/embeddedTomcat/../embeddedTomcat/webapps/../../../WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2014-03-06 12:22:22,810 [main] INFO  ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2014-03-06 12:22:22,820 [main] INFO  ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/WEB-INF/embeddedTomcat/../embeddedTomcat/webapps/../../../WEB-INF/classes/server.properties
=== 2014-03-06 12:22:22,828 [main] INFO  Logger - Logging system started.
=== 2014-03-06 12:22:22,830 [main] INFO  ISCInit - Auto-detected webRoot - using: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK
=== 2014-03-06 12:22:22,832 [main] INFO  ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.1p_2014-03-04/PowerEdition Deployment 2014-03-04) - Initialization Complete
=== 2014-03-06 12:22:22,881 [main] INFO  JSSyntaxScannerFilter - Couldn't load list of FileAssembler URLs - if the filter intercepts those URLs, they will be scanned.
=== 2014-03-06 12:22:22,927 [main] INFO  AuthenticationFilter - AuthExampleAuthenticator - URI Cache enabled - max size: 10000 entries
=== 2014-03-06 12:22:22,951 [main] INFO  [/] - Initializing Spring root WebApplicationContext
=== 2014-03-06 12:22:26,648 [main] INFO  TilesPlugin - Tiles definition factory loaded for module ''.
=== 2014-03-06 12:22:26,670 [main] INFO  ValidatorPlugIn - Loading validation rules file from '/WEB-INF/validator-rules.xml'
=== 2014-03-06 12:22:26,670 [main] INFO  ValidatorPlugIn - Loading validation rules file from '/WEB-INF/validation.xml'
=== 2014-03-06 12:22:26,791 [main] INFO  PreCache - Isomorphic PreCache servlet loading
=== 2014-03-06 12:22:26,830 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/builtinTypes.xml: 5ms
=== 2014-03-06 12:22:26,857 [main] INFO  PoolManager - SmartClient pooling started for 'simpleTypes' objects
=== 2014-03-06 12:22:26,946 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/DataSource.ds.xml: 5ms
=== 2014-03-06 12:22:26,997 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/DataSourceField.ds.xml: 4ms
=== 2014-03-06 12:22:27,038 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Validator.ds.xml: 2ms
=== 2014-03-06 12:22:27,059 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ValueMap.ds.xml: 1ms
=== 2014-03-06 12:22:27,064 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/InstantDataApp.ds.xml: 3ms
=== 2014-03-06 12:22:27,130 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Application.ds.xml: 1ms
=== 2014-03-06 12:22:27,146 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/IDAPage.ds.xml: 1ms
=== 2014-03-06 12:22:27,163 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/IDAUserType.ds.xml: 2ms
=== 2014-03-06 12:22:27,181 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/IDAOperation.ds.xml: 2ms
=== 2014-03-06 12:22:27,222 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Canvas.ds.xml: 15ms
=== 2014-03-06 12:22:27,313 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/MethodDeclaration.ds.xml: 1ms
=== 2014-03-06 12:22:27,323 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Img.ds.xml: 1ms
=== 2014-03-06 12:22:27,332 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/StatefulCanvas.ds.xml: 2ms
=== 2014-03-06 12:22:27,365 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Button.ds.xml: 1ms
=== 2014-03-06 12:22:27,374 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ListViewer.ds.xml: 1ms
=== 2014-03-06 12:22:27,386 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ListGrid.ds.xml: 9ms
=== 2014-03-06 12:22:27,459 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/DynamicForm.ds.xml: 5ms
=== 2014-03-06 12:22:27,497 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/FormItem.ds.xml: 8ms
=== 2014-03-06 12:22:27,535 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/DetailViewer.ds.xml: 2ms
=== 2014-03-06 12:22:27,546 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/DetailViewerField.ds.xml: 1ms
=== 2014-03-06 12:22:27,554 [main] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/isomorphicXML.ds.xml: 0ms
=== 2014-03-06 12:22:27,557 [main] INFO  PreCache - Isomorphic PreCache complete (766ms)
=== 2014-03-06 12:22:27,618 [main] INFO  Http11BaseProtocol - Initializing Coyote HTTP/1.1 on http-8080
=== 2014-03-06 12:22:27,633 [main] INFO  Http11BaseProtocol - Starting Coyote HTTP/1.1 on http-8080
=== 2014-03-06 12:23:24,530 [sor4] INFO  RequestContext - URL: '/tools/visualBuilder/workspace/smartclient_smaster_insert.jsp', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:23:25,365 [sor4] DEBUG XML - Parsed XML from (in memory stream): 1ms
=== 2014-03-06 12:23:25,368 [sor4] WARN  BasicDataSource - Attempting to set default i18n resource bundle name, encountered an unexpected context fieldName property: DataSource
=== 2014-03-06 12:23:25,369 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/shared/ds/smaster.ds.xml: 1ms
=== 2014-03-06 12:23:25,400 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/TextItem.ds.xml: 1ms
=== 2014-03-06 12:23:25,411 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/SubmitItem.ds.xml: 1ms
=== 2014-03-06 12:23:25,418 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ButtonItem.ds.xml: 1ms
=== 2014-03-06 12:23:25,431 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/CanvasItem.ds.xml: 1ms
=== 2014-03-06 12:23:25,445 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ResetItem.ds.xml: 1ms
=== 2014-03-06 12:23:25,462 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/ListGridField.ds.xml: 3ms
=== 2014-03-06 12:23:25,495 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/VLayout.ds.xml: 1ms
=== 2014-03-06 12:23:25,499 [sor4] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/Layout.ds.xml: 1ms
=== 2014-03-06 12:23:25,526 [sor4] INFO  JSSyntaxScannerFilter - /tools/visualBuilder/workspace/smartclient_smaster_insert.jsp: scanning <script> tags in html output
=== 2014-03-06 12:23:25,528 [sor4] INFO  Compression - /tools/visualBuilder/workspace/smartclient_smaster_insert.jsp: 1497 -> 589 bytes
=== 2014-03-06 12:24:12,878 [sor4] INFO  RequestContext - URL: '/tools/visualBuilder/workspace/smartclient_smaster_insert.jsp', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:24:12,955 [sor4] DEBUG LoadISCTag - isomorphicURI not specified in tag, defaulting to: ../../../isomorphic/
=== 2014-03-06 12:24:12,966 [sor4] DEBUG XML - Parsed XML from (in memory stream): 1ms
=== 2014-03-06 12:24:12,969 [sor4] WARN  BasicDataSource - Attempting to set default i18n resource bundle name, encountered an unexpected context fieldName property: DataSource
=== 2014-03-06 12:24:13,004 [sor4] INFO  JSSyntaxScannerFilter - /tools/visualBuilder/workspace/smartclient_smaster_insert.jsp: scanning <script> tags in html output
=== 2014-03-06 12:24:13,444 [sor2] INFO  RequestContext - URL: '/isomorphic/system/modules/ISC_VisualBuilder.js', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:24:13,445 [sor4] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/load_skin.js
=== 2014-03-06 12:24:13,485 [sor2] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/skin_styles.css
=== 2014-03-06 12:24:13,583 [sor2] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/images/blank.gif
=== 2014-03-06 12:24:13,659 [sor2] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/images/loadingSmall.gif
=== 2014-03-06 12:24:13,724 [sor2] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/images/Scrollbar/hscroll_sprite.png
=== 2014-03-06 12:24:13,733 [sor2] INFO  Download - Returning 304: Not modified on conditional get of: /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/skins/Graphite/images/Scrollbar/vscroll_sprite.png
=== 2014-03-06 12:24:13,756 [sor2] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: content-length:1005
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: cache-control:max-age=0
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: origin:http://localhost:8080
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36
=== 2014-03-06 12:24:13,760 [sor2] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - Header Name:Value pair: accept:*/*
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBuilder/workspace/smartclient_smaster_insert.jsp
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip,deflate,sdch
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,en;q=0.8
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A559%2C%20%0D%20%20%20%20top%3A120%2C%20%0D%20%20%20%20width%3A648%2C%20%0D%20%20%20%20height%3A520%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; menuPanel=visible; menuPanelType=main; PPA_ID=8s2d8ala3a4u6995c31vba78k7; webfx-tree-cookie-persistence=wfxt-4+wfxt-6+wfxt-10+wfxt-32+wfxt-34+wfxt-36; isc_cState=ready; JSESSIONID=A9DF5D5C89A17D9D312773A91BDF80C0
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - session exists: A9DF5D5C89A17D9D312773A91BDF80C0
=== 2014-03-06 12:24:13,761 [sor2] DEBUG IDACall - remote user: null
=== 2014-03-06 12:24:13,777 [sor2] DEBUG XML - Parsed XML from (in memory stream): 1ms
=== 2014-03-06 12:24:13,778 [sor2] DEBUG XML - Parsed XML from /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/schema/List.ds.xml: 0ms
=== 2014-03-06 12:24:13,781 [sor2] DEBUG RPCManager - Processing 1 requests.
=== 2014-03-06 12:24:13,790 [sor2] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"smaster",
        operationType:"fetch",
        textMatchStyle:"substring"
    },
    startRow:0,
    endRow:75,
    componentId:"ListGrid0",
    appID:"builtinApplication",
    operation:"smaster_fetch",
    oldValues:{
    }
}
=== 2014-03-06 12:24:13,792 [sor2] INFO  IDACall - Performing 1 operation(s)
=== 2014-03-06 12:24:13,794 [sor2] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2014-03-06 12:24:13,794 [sor2] DEBUG DeclarativeSecurity - DataSource smaster is not in the pre-checked list, processing...
=== 2014-03-06 12:24:13,800 [sor2] DEBUG AppBase - [builtinApplication.smaster_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2014-03-06 12:24:13,801 [sor2] DEBUG AppBase - [builtinApplication.smaster_fetch] No public zero-argument method named '_smaster_fetch' found, performing generic datasource operation
=== 2014-03-06 12:24:13,801 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_fetch] Performing fetch operation with
	criteria: {}	values: {}
=== 2014-03-06 12:24:13,812 [sor2] INFO  SQLWhereClause - [builtinApplication.smaster_fetch] empty condition
=== 2014-03-06 12:24:13,813 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2014-03-06 12:24:13,845 [sor2] DEBUG Velocity - SimpleLog4JLogSystem using category 'org.apache.Velocity'
=== 2014-03-06 12:24:13,845 [sor2] DEBUG Velocity - SimpleLog4JLogSystem using category 'org.apache.Velocity'
=== 2014-03-06 12:24:13,845 [sor2] DEBUG Velocity - Initializing Velocity, Calling init()...
=== 2014-03-06 12:24:13,845 [sor2] DEBUG Velocity - Initializing Velocity, Calling init()...
=== 2014-03-06 12:24:13,845 [sor2] DEBUG Velocity - *******************************************************************
<snipped velocity initiclization log messages to fit 35kb limit on txt files attachment>
=== 2014-03-06 12:24:13,893 [sor2] DEBUG Velocity - RuntimeInstance successfully initialized.
=== 2014-03-06 12:24:13,901 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
=== 2014-03-06 12:24:13,901 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_fetch] Eval'd row count query: SELECT COUNT(*) FROM public.smaster WHERE ('1'='1')
=== 2014-03-06 12:24:13,940 [sor2] INFO  PoolManager - [builtinApplication.smaster_fetch] SmartClient pooling started for 'USCProduction-localhost' objects
=== 2014-03-06 12:24:13,941 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.smaster_fetch] Initializing SQL config for 'USCProduction-localhost' from system config - using DriverManager:  org.postgresql.Driver
=== 2014-03-06 12:24:13,941 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.smaster_fetch] org.postgresql.Driver lookup successful
=== 2014-03-06 12:24:13,942 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.smaster_fetch] DriverManager fetching connection for USCProduction-localhost via jdbc url jdbc:postgresql://localhost:5432/USCProduction?user=xyz&password=zxyd
=== 2014-03-06 12:24:13,942 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.smaster_fetch] Passing JDBC URL only to getConnection
=== 2014-03-06 12:24:13,978 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.smaster_fetch] makeObject() created a pooled Connection '1640781879'
=== 2014-03-06 12:24:13,982 [sor2] DEBUG SQLConnectionManager - [builtinApplication.smaster_fetch] Borrowed connection '1640781879'
=== 2014-03-06 12:24:13,982 [sor2] DEBUG SQLTransaction - [builtinApplication.smaster_fetch] Started new USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:13,982 [sor2] DEBUG SQLDriver - [builtinApplication.smaster_fetch] About to execute SQL query in 'USCProduction-localhost' using connection '1640781879'
=== 2014-03-06 12:24:13,982 [sor2] INFO  SQLDriver - [builtinApplication.smaster_fetch] Executing SQL query on 'USCProduction-localhost': SELECT COUNT(*) FROM public.smaster WHERE ('1'='1')
=== 2014-03-06 12:24:14,020 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_fetch] Using SQL Limit query
=== 2014-03-06 12:24:14,020 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT smaster.custname, smaster.orderid FROM public.smaster WHERE ('1'='1') OFFSET 0 LIMIT 75
=== 2014-03-06 12:24:14,021 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT smaster.custname, smaster.orderid FROM public.smaster WHERE ('1'='1') OFFSET 0 LIMIT 75
=== 2014-03-06 12:24:14,022 [sor2] INFO  DSResponse - [builtinApplication.smaster_fetch] DSResponse: List with 8 items
=== 2014-03-06 12:24:14,022 [sor2] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2014-03-06 12:24:14,022 [sor2] DEBUG SQLTransaction - Committing USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:14,023 [sor2] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2014-03-06 12:24:14,027 [sor2] DEBUG SQLTransaction - getConnection() found transactional connection for USCProduction-localhost with hashcode "1640781879"
=== 2014-03-06 12:24:14,027 [sor2] DEBUG SQLTransaction - Ending USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:14,027 [sor2] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1640781879"
=== 2014-03-06 12:24:14,028 [sor2] INFO  Compression - /isomorphic/IDACall: 566 -> 305 bytes
=== 2014-03-06 12:24:28,100 [sor2] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:24:28,101 [sor2] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
=== 2014-03-06 12:24:28,101 [sor2] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
=== 2014-03-06 12:24:28,101 [sor2] DEBUG IDACall - Header Name:Value pair: content-length:907
=== 2014-03-06 12:24:28,101 [sor2] DEBUG IDACall - Header Name:Value pair: origin:http://localhost:8080
=== 2014-03-06 12:24:28,101 [sor2] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36
=== 2014-03-06 12:24:28,102 [sor2] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
=== 2014-03-06 12:24:28,102 [sor2] DEBUG IDACall - Header Name:Value pair: accept:*/*
=== 2014-03-06 12:24:28,102 [sor2] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBuilder/workspace/smartclient_smaster_insert.jsp
=== 2014-03-06 12:24:28,102 [sor2] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip,deflate,sdch
=== 2014-03-06 12:24:28,103 [sor2] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,en;q=0.8
=== 2014-03-06 12:24:28,103 [sor2] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A559%2C%20%0D%20%20%20%20top%3A120%2C%20%0D%20%20%20%20width%3A648%2C%20%0D%20%20%20%20height%3A520%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; menuPanel=visible; menuPanelType=main; PPA_ID=8s2d8ala3a4u6995c31vba78k7; webfx-tree-cookie-persistence=wfxt-4+wfxt-6+wfxt-10+wfxt-32+wfxt-34+wfxt-36; isc_cState=ready; JSESSIONID=A9DF5D5C89A17D9D312773A91BDF80C0
=== 2014-03-06 12:24:28,103 [sor2] DEBUG IDACall - session exists: A9DF5D5C89A17D9D312773A91BDF80C0
=== 2014-03-06 12:24:28,103 [sor2] DEBUG IDACall - remote user: null
=== 2014-03-06 12:24:28,106 [sor2] DEBUG XML - Parsed XML from (in memory stream): 1ms
=== 2014-03-06 12:24:28,110 [sor2] DEBUG RPCManager - Processing 1 requests.
=== 2014-03-06 12:24:28,110 [sor2] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    values:{
        custname:"cust1"
    },
    operationConfig:{
        dataSource:"smaster",
        operationType:"add",
        textMatchStyle:"exact"
    },
    componentId:"DynamicForm1",
    appID:"builtinApplication",
    operation:"smaster_add",
    oldValues:{
    },
    criteria:{
    }
}
=== 2014-03-06 12:24:28,111 [sor2] INFO  IDACall - Performing 1 operation(s)
=== 2014-03-06 12:24:28,114 [sor2] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2014-03-06 12:24:28,114 [sor2] DEBUG DeclarativeSecurity - DataSource smaster is not in the pre-checked list, processing...
=== 2014-03-06 12:24:28,114 [sor2] DEBUG AppBase - [builtinApplication.smaster_add] No userTypes defined, allowing anyone access to all operations for this application
=== 2014-03-06 12:24:28,114 [sor2] DEBUG AppBase - [builtinApplication.smaster_add] No public zero-argument method named '_smaster_add' found, performing generic datasource operation
=== 2014-03-06 12:24:28,115 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_add] Performing add operation with
	criteria: {custname:"cust1"}	values: {custname:"cust1"}
=== 2014-03-06 12:24:28,116 [sor2] DEBUG SQLValuesClause - [builtinApplication.smaster_add] Sequences: {orderid=__default}
=== 2014-03-06 12:24:28,119 [sor2] DEBUG SQLConnectionManager - [builtinApplication.smaster_add] Borrowed connection '1640781879'
=== 2014-03-06 12:24:28,119 [sor2] DEBUG SQLTransaction - [builtinApplication.smaster_add] Started new USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:28,119 [sor2] DEBUG SQLDriver - [builtinApplication.smaster_add] About to execute SQL update in 'USCProduction-localhost' using connection'1640781879'
=== 2014-03-06 12:24:28,119 [sor2] INFO  SQLDriver - [builtinApplication.smaster_add] Executing SQL update on 'USCProduction-localhost': INSERT INTO public.smaster (custname) VALUES ('cust1')
=== 2014-03-06 12:24:28,132 [sor2] DEBUG SQLDriver - [builtinApplication.smaster_add] Discovered multiple generated keys via JDBC: {}
=== 2014-03-06 12:24:28,132 [sor2] DEBUG SQLDataSource - [builtinApplication.smaster_add] add operation affected 1 rows
=== 2014-03-06 12:24:28,132 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_add] primaryKeys: {}
=== 2014-03-06 12:24:28,132 [sor2] DEBUG DeclarativeSecurity - [builtinApplication.smaster_add] Processing security checks for DataSource null, field null
=== 2014-03-06 12:24:28,132 [sor2] DEBUG DeclarativeSecurity - [builtinApplication.smaster_add] DataSource smaster is not in the pre-checked list, processing...
=== 2014-03-06 12:24:28,133 [sor2] DEBUG AppBase - [builtinApplication.smaster_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2014-03-06 12:24:28,133 [sor2] DEBUG AppBase - [builtinApplication.smaster_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2014-03-06 12:24:28,133 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_add, builtinApplication.null] Performing fetch operation with
	criteria: {}	values: {}
=== 2014-03-06 12:24:28,133 [sor2] INFO  SQLWhereClause - [builtinApplication.smaster_add, builtinApplication.null] empty condition
=== 2014-03-06 12:24:28,134 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2014-03-06 12:24:28,134 [sor2] INFO  SQLDataSource - [builtinApplication.smaster_add, builtinApplication.null] 73: Executing SQL query on 'USCProduction-localhost': SELECT smaster.custname, smaster.orderid FROM public.smaster WHERE ('1'='1')
=== 2014-03-06 12:24:28,134 [sor2] DEBUG SQLDriver - [builtinApplication.smaster_add, builtinApplication.null] About to execute SQL query in 'USCProduction-localhost' using connection '1640781879'
=== 2014-03-06 12:24:28,134 [sor2] INFO  SQLDriver - [builtinApplication.smaster_add, builtinApplication.null] Executing SQL query on 'USCProduction-localhost': SELECT smaster.custname, smaster.orderid FROM public.smaster WHERE ('1'='1')
=== 2014-03-06 12:24:28,136 [sor2] INFO  DSResponse - [builtinApplication.smaster_add, builtinApplication.null] DSResponse: List with 9 items
=== 2014-03-06 12:24:28,136 [sor2] INFO  DSResponse - [builtinApplication.smaster_add] DSResponse: List with 9 items
=== 2014-03-06 12:24:28,136 [sor2] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2014-03-06 12:24:28,136 [sor2] DEBUG SQLTransaction - Committing USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:28,163 [sor2] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2014-03-06 12:24:28,166 [sor2] DEBUG SQLTransaction - getConnection() found transactional connection for USCProduction-localhost with hashcode "1640781879"
=== 2014-03-06 12:24:28,167 [sor2] DEBUG SQLTransaction - Ending USCProduction-localhost transaction "1640781879"
=== 2014-03-06 12:24:28,167 [sor2] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1640781879"
=== 2014-03-06 12:24:28,168 [sor2] INFO  Compression - /isomorphic/IDACall: 576 -> 298 bytes
=== 2014-03-06 12:25:11,173 [sor2] INFO  RequestContext - URL: '/tools/visualBuilder/workspace/smasterinsert.jsp', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:25:11,271 [sor2] DEBUG LoadISCTag - isomorphicURI not specified in tag, defaulting to: ../../../isomorphic/
-------------In masterdetail.jsp inserting value with stmt=insert into smaster (custname) values('kk1463447358') RETURNING *
-------------In masterdetail.jsp returned primary key value=1216030680024759
=== 2014-03-06 12:25:11,344 [sor2] INFO  JSSyntaxScannerFilter - /tools/visualBuilder/workspace/smasterinsert.jsp: scanning <script> tags in html output
=== 2014-03-06 12:25:11,345 [sor2] INFO  Compression - /tools/visualBuilder/workspace/smasterinsert.jsp: 1890 -> 443 bytes
=== 2014-03-06 12:25:11,701 [sor2] INFO  RequestContext - URL: '/isomorphic/system/modules/ISC_DocViewer.js', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:25:11,701 [sor3] INFO  RequestContext - URL: '/isomorphic/system/modules/ISC_VisualBuilder.js', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:25:11,707 [sor3] INFO  Download - File /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/modules/ISC_VisualBuilder.js not found, sending 404
=== 2014-03-06 12:25:11,708 [sor2] INFO  Download - File /home/kk/Downloads/WSO2/projects/SmartClient_v91p_2014-03-04_PowerEdition/smartclientSDK/isomorphic/system/modules/ISC_DocViewer.js not found, sending 404
=== 2014-03-06 12:25:17,064 [sor2] INFO  RequestContext - URL: '/tools/visualBuilder/workspace/smasterinsert.jsp', User-Agent: 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/32.0.1700.107 Chrome/32.0.1700.107 Safari/537.36': Safari with Accept-Encoding header
=== 2014-03-06 12:25:17,066 [sor2] DEBUG LoadISCTag - isomorphicURI not specified in tag, defaulting to: ../../../isomorphic/
-------------In masterdetail.jsp inserting value with stmt=insert into smaster (custname) values('kk-974069290') RETURNING *
-------------In masterdetail.jsp returned primary key value=1216030869849784