=== 2013-07-31 20:58:39,639 [l0-4] INFO  RequestContext - URL: '/Orchestrate/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0': Moz (Gecko) with Accept-Encoding header
=== 2013-07-31 20:58:39,639 [l0-4] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:58:39,639 [l0-4] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0
=== 2013-07-31 20:58:39,639 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/Orchestrate.html?gwt.codesvr=127.0.0.1:9997
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Content-Type:multipart/form-data; boundary=---------------------------11617136068440
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - Header Name:Value pair: Content-Length:28171
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:58:39,640 [l0-4] DEBUG IDACall - remote user: null
=== 2013-07-31 20:58:39,686 [l0-4] DEBUG XML - Parsed XML from (in memory stream): 4ms
=== 2013-07-31 20:58:39,690 [l0-4] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:58:39,693 [l0-4] INFO  DSRequest - We parsed 1 uploaded file(s): "Taxonomy 1.xlsx" (26789 bytes)
=== 2013-07-31 20:58:39,696 [l0-4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    values:{
        UploadedFile:"Taxonomy 1.xlsx",
        AccountID:1,
        Name:"Taxonomy 1",
        Type:"TAXONOMY",
        UploadedFile_filesize:26789,
        UploadedFile_date_created:new Date(1375329519693),
        UploadedFile_filename:"Taxonomy 1.xlsx"
    },
    operationConfig:{
        dataSource:"uploads",
        operationType:"add"
    },
    componentId:"isc_DynamicForm_7",
    appID:"builtinApplication",
    operation:"uploads_add",
    oldValues:{
    },
    criteria:{
    }
}
=== 2013-07-31 20:58:39,696 [l0-4] INFO  IDACall - Performing 1 operation(s)
=== 2013-07-31 20:58:39,696 [l0-4] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-07-31 20:58:39,696 [l0-4] DEBUG DeclarativeSecurity - DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:58:39,696 [l0-4] DEBUG AppBase - [builtinApplication.uploads_add] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:58:39,696 [l0-4] DEBUG AppBase - [builtinApplication.uploads_add] No public zero-argument method named '_uploads_add' found, performing generic datasource operation
=== 2013-07-31 20:58:39,698 [l0-4] INFO  SQLDataSource - [builtinApplication.uploads_add] Performing add operation with
	criteria: {AccountID:1,Name:"Taxonomy 1",Type:"TAXONOMY",UploadedFile_filesize:26789,UploadedFile_date_created:new Date(1375329519693),UploadedFile_filename:"Taxonomy 1.xlsx"}	values: {AccountID:1,Name:"Taxonomy 1",Type:"TAXONOMY",UploadedFile_filesize:26789,UploadedFile_date_created:new Date(1375329519693),UploadedFile_filename:"Taxonomy 1.xlsx"}
=== 2013-07-31 20:58:39,700 [l0-4] DEBUG SQLValuesClause - [builtinApplication.uploads_add] Sequences: {ID=__default}
=== 2013-07-31 20:58:39,704 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_add] Executing pingTest 'select 1 from dual' on connection 225615871
=== 2013-07-31 20:58:39,704 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.uploads_add] Returning borrowed connection '225615871'
=== 2013-07-31 20:58:39,704 [l0-4] DEBUG SQLDriver - [builtinApplication.uploads_add] About to execute SQL update in 'Mysql' using connection'225615871'
=== 2013-07-31 20:58:39,704 [l0-4] INFO  SQLDriver - [builtinApplication.uploads_add] Executing SQL update on 'Mysql': INSERT INTO uploads (AccountID, Name, Type, UploadedFile, UploadedFile_date_created, UploadedFile_filename, UploadedFile_filesize) VALUES (1, 'Taxonomy 1', 'TAXONOMY', ?, '2013-07-31 20:58:39', 'Taxonomy 1.xlsx', 26789)
=== 2013-07-31 20:58:39,913 [l0-4] DEBUG SQLDataSource - [builtinApplication.uploads_add] add operation affected 1 rows
=== 2013-07-31 20:58:39,914 [l0-4] DEBUG MysqlDriver - [builtinApplication.uploads_add] fetchLastRow data - primaryKeysPresent: {}sequencesNotPresent: [ID]
=== 2013-07-31 20:58:39,914 [l0-4] DEBUG SQLDriver - [builtinApplication.uploads_add] About to execute SQL query in 'Mysql' using connection '225615871'
=== 2013-07-31 20:58:39,914 [l0-4] INFO  SQLDriver - [builtinApplication.uploads_add] Executing SQL query on 'Mysql': SELECT LAST_INSERT_ID()
=== 2013-07-31 20:58:39,916 [l0-4] INFO  SQLDataSource - [builtinApplication.uploads_add] primaryKeys: {ID=70}
=== 2013-07-31 20:58:39,916 [l0-4] DEBUG DeclarativeSecurity - [builtinApplication.uploads_add] Processing security checks for DataSource null, field null
=== 2013-07-31 20:58:39,916 [l0-4] DEBUG DeclarativeSecurity - [builtinApplication.uploads_add] DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:58:39,916 [l0-4] DEBUG AppBase - [builtinApplication.uploads_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:58:39,916 [l0-4] DEBUG AppBase - [builtinApplication.uploads_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-07-31 20:58:39,917 [l0-4] INFO  SQLDataSource - [builtinApplication.uploads_add, builtinApplication.null] Performing fetch operation with
	criteria: {ID:70}	values: {ID:70}
=== 2013-07-31 20:58:39,917 [l0-4] INFO  SQLDataSource - [builtinApplication.uploads_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:58:39,918 [l0-4] INFO  SQLDataSource - [builtinApplication.uploads_add, builtinApplication.null] Executing SQL query on 'Mysql': SELECT uploads.AccountID, uploads.ID, uploads.Name, uploads.Type, uploads.UploadedFile, uploads.UploadedFile_date_created, uploads.UploadedFile_filename, uploads.UploadedFile_filesize FROM uploads WHERE (uploads.ID=70)
=== 2013-07-31 20:58:39,918 [l0-4] DEBUG SQLDriver - [builtinApplication.uploads_add, builtinApplication.null] About to execute SQL query in 'Mysql' using connection '225615871'
=== 2013-07-31 20:58:39,918 [l0-4] INFO  SQLDriver - [builtinApplication.uploads_add, builtinApplication.null] Executing SQL query on 'Mysql': SELECT uploads.AccountID, uploads.ID, uploads.Name, uploads.Type, uploads.UploadedFile, uploads.UploadedFile_date_created, uploads.UploadedFile_filename, uploads.UploadedFile_filesize FROM uploads WHERE (uploads.ID=70)
=== 2013-07-31 20:58:39,923 [l0-4] INFO  DSResponse - [builtinApplication.uploads_add, builtinApplication.null] DSResponse: List with 1 items
=== 2013-07-31 20:58:39,923 [l0-4] INFO  DSResponse - [builtinApplication.uploads_add] DSResponse: List with 1 items
=== 2013-07-31 20:58:39,923 [l0-4] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "225615871"
=== 2013-07-31 20:58:39,923 [l0-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 225615871
=== 2013-07-31 20:58:39,924 [l0-4] DEBUG RPCManager - Content type for RPC transaction: text/html; charset=UTF-8
=== 2013-07-31 20:58:39,927 [l0-4] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-07-31 20:58:39,932 [l0-4] DEBUG SQLTransaction - Returning transactional connection for Mysql (connection is null)
=== 2013-07-31 20:58:39,933 [l0-4] INFO  Compression - /Orchestrate/sc/IDACall: 816 -> 502 bytes
=== 2013-07-31 20:58:41,430 [l0-3] INFO  RequestContext - URL: '/Orchestrate/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0': Moz (Gecko) with Accept-Encoding header
=== 2013-07-31 20:58:41,431 [l0-3] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:58:41,431 [l0-3] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0
=== 2013-07-31 20:58:41,431 [l0-3] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-07-31 20:58:41,431 [l0-3] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/Orchestrate.html?gwt.codesvr=127.0.0.1:9997
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Content-Length:1012
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:58:41,432 [l0-3] DEBUG IDACall - remote user: null
=== 2013-07-31 20:58:41,431 [l0-0] INFO  RequestContext - URL: '/Orchestrate/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0': Moz (Gecko) with Accept-Encoding header
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:58:41,434 [l0-0] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/Orchestrate.html?gwt.codesvr=127.0.0.1:9997
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:1204
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:58:41,435 [l0-0] DEBUG IDACall - remote user: null
=== 2013-07-31 20:58:41,439 [l0-3] DEBUG XML - Parsed XML from (in memory stream): 5ms
=== 2013-07-31 20:58:41,447 [l0-3] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:58:41,452 [l0-3] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"uploads",
        operationType:"fetch",
        textMatchStyle:"substring"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_UploadList_1",
    appID:"builtinApplication",
    operation:"uploads_fetch",
    oldValues:{
    }
}
=== 2013-07-31 20:58:41,452 [l0-3] INFO  IDACall - Performing 1 operation(s)
=== 2013-07-31 20:58:41,452 [l0-3] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-07-31 20:58:41,452 [l0-3] DEBUG DeclarativeSecurity - DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:58:41,453 [l0-3] DEBUG AppBase - [builtinApplication.uploads_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:58:41,453 [l0-3] DEBUG AppBase - [builtinApplication.uploads_fetch] No public zero-argument method named '_uploads_fetch' found, performing generic datasource operation
=== 2013-07-31 20:58:41,454 [l0-3] INFO  SQLDataSource - [builtinApplication.uploads_fetch] Performing fetch operation with
	criteria: {}	values: {}
=== 2013-07-31 20:58:41,454 [l0-3] INFO  SQLWhereClause - [builtinApplication.uploads_fetch] empty condition
=== 2013-07-31 20:58:41,454 [l0-3] INFO  SQLDataSource - [builtinApplication.uploads_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:58:41,454 [l0-3] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:58:41,455 [l0-3] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Eval'd row count query: SELECT COUNT(*) FROM uploads WHERE ('1'='1')
=== 2013-07-31 20:58:41,455 [l0-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 225615871
=== 2013-07-31 20:58:41,455 [l0-3] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] Returning borrowed connection '225615871'
=== 2013-07-31 20:58:41,456 [l0-3] DEBUG SQLDriver - [builtinApplication.uploads_fetch] About to execute SQL query in 'Mysql' using connection '225615871'
=== 2013-07-31 20:58:41,456 [l0-3] INFO  SQLDriver - [builtinApplication.uploads_fetch] Executing SQL query on 'Mysql': SELECT COUNT(*) FROM uploads WHERE ('1'='1')
=== 2013-07-31 20:58:41,456 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 5ms
=== 2013-07-31 20:58:41,457 [l0-3] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Using SQL Limit query
=== 2013-07-31 20:58:41,457 [l0-3] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT uploads.AccountID, uploads.ID, uploads.Name, uploads.Type, uploads.UploadedFile, uploads.UploadedFile_date_created, uploads.UploadedFile_filename, uploads.UploadedFile_filesize FROM uploads WHERE ('1'='1') LIMIT 0, 75
=== 2013-07-31 20:58:41,457 [l0-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:58:41,457 [l0-3] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] Returning borrowed connection '1785359542'
=== 2013-07-31 20:58:41,459 [l0-3] INFO  DSResponse - [builtinApplication.uploads_fetch] DSResponse: List with 1 items
=== 2013-07-31 20:58:41,459 [l0-3] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] About to close PoolableConnection with hashcode "1785359542"
=== 2013-07-31 20:58:41,459 [l0-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:58:41,460 [l0-3] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-07-31 20:58:41,460 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:58:41,463 [l0-3] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-07-31 20:58:41,464 [l0-0] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        AccountID:1,
        Type:"TAXONOMY"
    },
    operationConfig:{
        dataSource:"uploads",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_UploadList_1",
    appID:"builtinApplication",
    operation:"uploads_fetch",
    oldValues:{
        AccountID:1,
        Type:"TAXONOMY"
    }
}
=== 2013-07-31 20:58:41,464 [l0-3] DEBUG SQLTransaction - Returning transactional connection for Mysql (connection is null)
=== 2013-07-31 20:58:41,464 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-07-31 20:58:41,464 [l0-3] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "225615871"
=== 2013-07-31 20:58:41,464 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-07-31 20:58:41,464 [l0-3] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 225615871
=== 2013-07-31 20:58:41,464 [l0-0] DEBUG DeclarativeSecurity - DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:58:41,465 [l0-0] DEBUG AppBase - [builtinApplication.uploads_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:58:41,465 [l0-0] DEBUG AppBase - [builtinApplication.uploads_fetch] No public zero-argument method named '_uploads_fetch' found, performing generic datasource operation
=== 2013-07-31 20:58:41,466 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_fetch] Performing fetch operation with
	criteria: {AccountID:1,Type:"TAXONOMY"}	values: {AccountID:1,Type:"TAXONOMY"}
=== 2013-07-31 20:58:41,466 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:58:41,467 [l0-0] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:58:41,467 [l0-0] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Eval'd row count query: SELECT COUNT(*) FROM uploads WHERE (uploads.AccountID=1 AND uploads.Type='TAXONOMY')
=== 2013-07-31 20:58:41,467 [l0-0] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:58:41,468 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] Returning borrowed connection '1785359542'
=== 2013-07-31 20:58:41,468 [l0-0] DEBUG SQLDriver - [builtinApplication.uploads_fetch] About to execute SQL query in 'Mysql' using connection '1785359542'
=== 2013-07-31 20:58:41,468 [l0-0] INFO  SQLDriver - [builtinApplication.uploads_fetch] Executing SQL query on 'Mysql': SELECT COUNT(*) FROM uploads WHERE (uploads.AccountID=1 AND uploads.Type='TAXONOMY')
=== 2013-07-31 20:58:41,470 [l0-0] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] Using SQL Limit query
=== 2013-07-31 20:58:41,470 [l0-0] DEBUG SQLDataSource - [builtinApplication.uploads_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT uploads.AccountID, uploads.ID, uploads.Name, uploads.Type, uploads.UploadedFile, uploads.UploadedFile_date_created, uploads.UploadedFile_filename, uploads.UploadedFile_filesize FROM uploads WHERE (uploads.AccountID=1 AND uploads.Type='TAXONOMY') LIMIT 0, 75
=== 2013-07-31 20:58:41,471 [l0-0] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 1896306510
=== 2013-07-31 20:58:41,471 [l0-3] INFO  Compression - /Orchestrate/sc/IDACall: 344 -> 259 bytes
=== 2013-07-31 20:58:41,471 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] Returning borrowed connection '1896306510'
=== 2013-07-31 20:58:41,473 [l0-0] INFO  DSResponse - [builtinApplication.uploads_fetch] DSResponse: List with 1 items
=== 2013-07-31 20:58:41,473 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.uploads_fetch] About to close PoolableConnection with hashcode "1896306510"
=== 2013-07-31 20:58:41,473 [l0-0] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_fetch] Executing pingTest 'select 1 from dual' on connection 1896306510
=== 2013-07-31 20:58:41,474 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-07-31 20:58:41,477 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-07-31 20:58:41,478 [l0-0] DEBUG SQLTransaction - Returning transactional connection for Mysql (connection is null)
=== 2013-07-31 20:58:41,478 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1785359542"
=== 2013-07-31 20:58:41,478 [l0-0] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:58:41,479 [l0-0] INFO  Compression - /Orchestrate/sc/IDACall: 344 -> 259 bytes
=== 2013-07-31 20:58:43,194 [l0-0] INFO  RequestContext - URL: '/Orchestrate/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0': Moz (Gecko) with Accept-Encoding header
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:22.0) Gecko/20100101 Firefox/22.0
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/Orchestrate.html?gwt.codesvr=127.0.0.1:9997
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:846
=== 2013-07-31 20:58:43,194 [l0-0] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-07-31 20:58:43,195 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-07-31 20:58:43,195 [l0-0] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:58:43,195 [l0-0] DEBUG IDACall - remote user: null
=== 2013-07-31 20:58:43,199 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-07-31 20:58:43,203 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:58:43,203 [l0-0] DEBUG RPCManager - Request #1 (RPCRequest) data: {
    appID:"CedarCone",
    className:"com.cedarcone.Orchestrate.server.RPCResponder",
    methodName:"getTaxonomyMetaData",
    arguments:[
        70,
        "#CHARACTERISTIC#",
        "#PROPERTY#"
    ],
    is_ISC_RPC_DMI:true
}
=== 2013-07-31 20:58:43,204 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-07-31 20:58:45,283 [l0-0] DEBUG XML - Parsed XML from __USE_CONTAINER__/shared/app/CedarCone.app.xml: 2070ms
=== 2013-07-31 20:58:45,290 [l0-0] DEBUG XML - Parsed XML from __USE_CONTAINER__/Orchestrate/sc/system/schema/Application.ds.xml: 4ms
=== 2013-07-31 20:58:45,314 [l0-0] DEBUG XML - Parsed XML from __USE_CONTAINER__/Orchestrate/sc/system/schema/Method.ds.xml: 4ms
=== 2013-07-31 20:58:45,320 [l0-0] DEBUG RPCDMI - appConfig: isc.Application.create({
    rpcBindings:[
        {
            ID:"SampleRPC",
            className:"com.cedarcone.Orchestrate.server.samples.SampleRPC",
            visibleMethods:[
                {
                    name:"reverseString"
                },
                {
                    name:"downloadFile"
                }
            ]
        },
        {
            ID:"RPCResponder",
            className:"com.cedarcone.Orchestrate.server.RPCResponder",
            visibleMethods:[
                {
                    name:"queryTaxonomy"
                },
                {
                    name:"getTaxonomyMetaData"
                },
                {
                    name:"getClassifierStatus"
                },
                {
                    name:"test"
                }
            ]
        }
    ]
})

SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/P:/EclipseWorkSpaces/Scott-Tiger/Orchestrate/target/Orchestrate-1.5/WEB-INF/lib/slf4j-jdk14-1.6.4.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/P:/EclipseWorkSpaces/Scott-Tiger/Orchestrate/target/Orchestrate-1.5/WEB-INF/lib/slf4j-log4j12-1.6.1.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
Jul 31, 2013 8:58:48 PM org.hibernate.annotations.common.Version <clinit>
INFO: Hibernate Commons Annotations 3.2.0.Final
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.Environment <clinit>
INFO: Hibernate 3.6.10.Final
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.Environment <clinit>
INFO: hibernate.properties not found
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.Environment buildBytecodeProvider
INFO: Bytecode provider name : javassist
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.Environment <clinit>
INFO: using JDK 1.4 java.sql.Timestamp handling
=== 2013-07-31 20:58:48,550 [l0-0] INFO  ServerLogger - Orchestrate: hibernate.HibernateProperties: {hibernate.connection.username=Cedarcone, hibernate.default_schema=cedarcone, hibernate.connection.password=Cedarcone2013!, hibernate.dialect=org.hibernate.dialect.MySQL5Dialect, hibernate.connection.url=jdbc:mysql://localhost, hibernate.connection.driver_class=com.mysql.jdbc.Driver}
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.AnnotationBinder bindClass
INFO: Binding entity from annotated class: com.cedarcone.Orchestrate.server.model.Uploads
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.annotations.EntityBinder bindTable
INFO: Bind entity com.cedarcone.Orchestrate.server.model.Uploads on table Uploads
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.Configuration applyHibernateValidatorLegacyConstraintsOnDDL
INFO: Hibernate Validator not found: ignoring
Jul 31, 2013 8:58:48 PM org.hibernate.validator.util.Version <clinit>
INFO: Hibernate Validator 4.2.0.Final
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.search.HibernateSearchEventListenerRegister enableHibernateSearch
INFO: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
Jul 31, 2013 8:58:48 PM org.hibernate.connection.DriverManagerConnectionProvider configure
INFO: Using Hibernate built-in connection pool (not for production use!)
Jul 31, 2013 8:58:48 PM org.hibernate.connection.DriverManagerConnectionProvider configure
INFO: Hibernate connection pool size: 20
Jul 31, 2013 8:58:48 PM org.hibernate.connection.DriverManagerConnectionProvider configure
INFO: autocommit mode: false
Jul 31, 2013 8:58:48 PM org.hibernate.connection.DriverManagerConnectionProvider configure
INFO: using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://localhost
Jul 31, 2013 8:58:48 PM org.hibernate.connection.DriverManagerConnectionProvider configure
INFO: connection properties: {user=Cedarcone, password=****}
Jul 31, 2013 8:58:48 PM org.hibernate.dialect.Dialect <init>
INFO: Using dialect: org.hibernate.dialect.MySQL5Dialect
Jul 31, 2013 8:58:48 PM org.hibernate.engine.jdbc.JdbcSupportLoader useContextualLobCreation
INFO: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Database ->
       name : MySQL
    version : 5.6.12
      major : 5
      minor : 6
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Driver ->
       name : MySQL-AB JDBC Driver
    version : mysql-connector-java-5.1.6 ( Revision: ${svn.Revision} )
      major : 5
      minor : 1
Jul 31, 2013 8:58:48 PM org.hibernate.transaction.TransactionFactoryFactory buildTransactionFactory
INFO: Using default transaction strategy (direct JDBC transactions)
Jul 31, 2013 8:58:48 PM org.hibernate.transaction.TransactionManagerLookupFactory getTransactionManagerLookup
INFO: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Automatic flush during beforeCompletion(): disabled
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Automatic session close at end of transaction: disabled
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: JDBC batch size: 15
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: JDBC batch updates for versioned data: disabled
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Scrollable result sets: enabled
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: JDBC3 getGeneratedKeys(): enabled
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Connection release mode: auto
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Default schema: cedarcone
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Maximum outer join fetch depth: 2
Jul 31, 2013 8:58:48 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Default batch fetch size: 1
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Generate SQL with comments: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Order SQL updates by primary key: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Order SQL inserts for batching: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory createQueryTranslatorFactory
INFO: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
Jul 31, 2013 8:58:49 PM org.hibernate.hql.ast.ASTQueryTranslatorFactory <init>
INFO: Using ASTQueryTranslatorFactory
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Query language substitutions: {}
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: JPA-QL strict compliance: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Second-level cache: enabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Query cache: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory createRegionFactory
INFO: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Optimize cache for minimal puts: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Structured second-level cache entries: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Statistics: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Deleted entity synthetic identifier rollback: disabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Default entity-mode: pojo
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Named query checking : enabled
Jul 31, 2013 8:58:49 PM org.hibernate.cfg.SettingsFactory buildSettings
INFO: Check Nullability in Core (should be disabled when Bean Validation is on): disabled
Jul 31, 2013 8:58:49 PM org.hibernate.impl.SessionFactoryImpl <init>
INFO: building session factory
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@234caaa5
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7e5748bd
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7e5748bd
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@1468b118
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@6542a91f
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@7b6d6628
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@4b4e0b66
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [blob] overrides previous : org.hibernate.type.BlobType@70ce7c29
Jul 31, 2013 8:58:49 PM org.hibernate.type.BasicTypeRegistry register
INFO: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@70ce7c29
Jul 31, 2013 8:58:49 PM org.hibernate.impl.SessionFactoryObjectFactory addInstance
INFO: Not binding factory to JNDI, no JNDI name configured
getInputStreamFromUploadID: uploadID=70
getInputStreamFromUploadID: upload=Uploads [accountID=1, ID=70, name=Taxonomy 1, type=TAXONOMY, UploadedFile=com.mysql.jdbc.Blob@321c62f9, uploadedFile_date_created=2013-07-31 20:58:39.0, uploadedFile_filename=Taxonomy 1.xlsx, uploadedFile_filesize=26789]
getInputStreamFromUploadID: blob=com.mysql.jdbc.Blob@321c62f9
=== 2013-07-31 20:58:50,096 [l0-0] INFO  RPCResponder - result=#CHARACTERISTIC#[Title]#CHARACTERISTIC# #PROPERTY#[Function, Sub-function, Level, Company Function, Company Channels, Company Products, Company Size - Revenue Range, Company Size - Employee Range, Industry SIC Level 1, Industry SIC Level 2, Industry SIC Level 3, Industry SIC Level 4]#PROPERTY#
=== 2013-07-31 20:58:50,096 [l0-0] DEBUG RPCDMI - rpc returned data
=== 2013-07-31 20:58:50,096 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-07-31 20:58:50,097 [l0-0] INFO  Compression - /Orchestrate/sc/IDACall: 359 -> 208 bytes
Orchestrate: controller.ServerCall$1: call results for getTaxonomyMetaData results=#CHARACTERISTIC#[Title]#CHARACTERISTIC# #PROPERTY#[Function, Sub-function, Level, Company Function, Company Channels, Company Products, Company Size - Revenue Range, Company Size - Employee Range, Industry SIC Level 1, Industry SIC Level 2, Industry SIC Level 3, Industry SIC Level 4]#PROPERTY#