Orchestrate: viewer.shared.elements.Items$13$3: ClickHandler: getValue=null
Orchestrate: viewer.shared.elements.Items$13$2: ChangeHandler: getValue=null
Orchestrate: viewer.shared.elements.Items$13$1: ChangedHandler: getValue=[Taxonomy 2.xlsx]
Orchestrate: controller.Actions$2: value=[Taxonomy 2.xlsx]
=== 2013-07-31 20:59:00,586 [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:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:59:00,587 [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:59:00,587 [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:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:59:00,587 [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:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:multipart/form-data; boundary=---------------------------228662797010542
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:28317
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:59:00,587 [l0-0] DEBUG IDACall - remote user: null
=== 2013-07-31 20:59:00,593 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-07-31 20:59:00,598 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:59:00,601 [l0-0] INFO  DSRequest - We parsed 1 uploaded file(s): "Taxonomy 2.xlsx" (26789 bytes)
=== 2013-07-31 20:59:00,603 [l0-0] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    values:{
        UploadedFile:"Taxonomy 2.xlsx",
        AccountID:1,
        Name:"Taxonomy 2",
        Type:"TAXONOMY",
        UploadedFile_filesize:26789,
        UploadedFile_date_created:new Date(1375329540601),
        ID:null,
        UploadedFile_filename:"Taxonomy 2.xlsx"
    },
    operationConfig:{
        dataSource:"uploads",
        operationType:"add"
    },
    componentId:"isc_DynamicForm_7",
    appID:"builtinApplication",
    operation:"uploads_add",
    oldValues:{
    },
    criteria:{
    }
}
=== 2013-07-31 20:59:00,603 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-07-31 20:59:00,603 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-07-31 20:59:00,604 [l0-0] DEBUG DeclarativeSecurity - DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:59:00,604 [l0-0] DEBUG AppBase - [builtinApplication.uploads_add] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:59:00,604 [l0-0] DEBUG AppBase - [builtinApplication.uploads_add] No public zero-argument method named '_uploads_add' found, performing generic datasource operation
=== 2013-07-31 20:59:00,605 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_add] Performing add operation with
	criteria: {AccountID:1,Name:"Taxonomy 2",Type:"TAXONOMY",UploadedFile_filesize:26789,UploadedFile_date_created:new Date(1375329540601),ID:null,UploadedFile_filename:"Taxonomy 2.xlsx"}	values: {AccountID:1,Name:"Taxonomy 2",Type:"TAXONOMY",UploadedFile_filesize:26789,UploadedFile_date_created:new Date(1375329540601),ID:null,UploadedFile_filename:"Taxonomy 2.xlsx"}
=== 2013-07-31 20:59:00,605 [l0-0] DEBUG SQLValuesClause - [builtinApplication.uploads_add] Sequences: {ID=__default}
=== 2013-07-31 20:59:00,606 [l0-0] DEBUG PoolableSQLConnectionFactory - [builtinApplication.uploads_add] Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:59:00,607 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.uploads_add] Returning borrowed connection '1785359542'
=== 2013-07-31 20:59:00,607 [l0-0] DEBUG SQLDriver - [builtinApplication.uploads_add] About to execute SQL update in 'Mysql' using connection'1785359542'
=== 2013-07-31 20:59:00,607 [l0-0] 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 2', 'TAXONOMY', ?, '2013-07-31 20:59:00', 'Taxonomy 2.xlsx', 26789)
=== 2013-07-31 20:59:00,632 [l0-0] DEBUG SQLDataSource - [builtinApplication.uploads_add] add operation affected 1 rows
=== 2013-07-31 20:59:00,632 [l0-0] DEBUG MysqlDriver - [builtinApplication.uploads_add] fetchLastRow data - primaryKeysPresent: {}sequencesNotPresent: [ID]
=== 2013-07-31 20:59:00,632 [l0-0] DEBUG SQLDriver - [builtinApplication.uploads_add] About to execute SQL query in 'Mysql' using connection '1785359542'
=== 2013-07-31 20:59:00,632 [l0-0] INFO  SQLDriver - [builtinApplication.uploads_add] Executing SQL query on 'Mysql': SELECT LAST_INSERT_ID()
=== 2013-07-31 20:59:00,633 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_add] primaryKeys: {ID=71}
=== 2013-07-31 20:59:00,634 [l0-0] DEBUG DeclarativeSecurity - [builtinApplication.uploads_add] Processing security checks for DataSource null, field null
=== 2013-07-31 20:59:00,634 [l0-0] DEBUG DeclarativeSecurity - [builtinApplication.uploads_add] DataSource uploads is not in the pre-checked list, processing...
=== 2013-07-31 20:59:00,634 [l0-0] DEBUG AppBase - [builtinApplication.uploads_add, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-07-31 20:59:00,634 [l0-0] DEBUG AppBase - [builtinApplication.uploads_add, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-07-31 20:59:00,634 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_add, builtinApplication.null] Performing fetch operation with
	criteria: {ID:71}	values: {ID:71}
=== 2013-07-31 20:59:00,635 [l0-0] INFO  SQLDataSource - [builtinApplication.uploads_add, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-07-31 20:59:00,635 [l0-0] 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=71)
=== 2013-07-31 20:59:00,635 [l0-0] DEBUG SQLDriver - [builtinApplication.uploads_add, builtinApplication.null] About to execute SQL query in 'Mysql' using connection '1785359542'
=== 2013-07-31 20:59:00,635 [l0-0] 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=71)
=== 2013-07-31 20:59:00,637 [l0-0] INFO  DSResponse - [builtinApplication.uploads_add, builtinApplication.null] DSResponse: List with 1 items
=== 2013-07-31 20:59:00,637 [l0-0] INFO  DSResponse - [builtinApplication.uploads_add] DSResponse: List with 1 items
=== 2013-07-31 20:59:00,637 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1785359542"
=== 2013-07-31 20:59:00,637 [l0-0] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1785359542
=== 2013-07-31 20:59:00,638 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/html; charset=UTF-8
=== 2013-07-31 20:59:00,640 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-07-31 20:59:00,643 [l0-0] DEBUG SQLTransaction - Returning transactional connection for Mysql (connection is null)
=== 2013-07-31 20:59:00,643 [l0-0] INFO  Compression - /Orchestrate/sc/IDACall: 817 -> 503 bytes
=== 2013-07-31 20:59:01,582 [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:59:01,582 [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:59:01,582 [l0-3] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:59:01,582 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
=== 2013-07-31 20:59:01,582 [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:59:01,582 [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:59:01,582 [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:59:01,582 [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:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:59:01,583 [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:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Content-Length:1204
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:59:01,583 [l0-3] DEBUG IDACall - remote user: null
=== 2013-07-31 20:59:01,582 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-07-31 20:59:01,583 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-07-31 20:59:01,583 [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:59:01,583 [l0-0] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=34memya4gpsi
=== 2013-07-31 20:59:01,583 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-07-31 20:59:01,583 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-07-31 20:59:01,583 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:1014
=== 2013-07-31 20:59:01,584 [l0-0] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-07-31 20:59:01,584 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-07-31 20:59:01,584 [l0-0] DEBUG IDACall - session exists: 34memya4gpsi
=== 2013-07-31 20:59:01,584 [l0-0] DEBUG IDACall - remote user: null
=== 2013-07-31 20:59:01,587 [l0-3] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-07-31 20:59:01,591 [l0-3] DEBUG RPCManager - Processing 1 requests.
=== 2013-07-31 20:59:01,591 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 5ms
=== 2013-07-31 20:59:01,593 [l0-3] 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"
    }
}